Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512220009\orcl_vkrm_12076_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:37407M/63366M, Ph+PgF:44689M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12076, image: ORACLE.EXE (VKRM) *** 2025-05-12 22:00:09.188 *** SESSION ID:(177.40573) 2025-05-12 22:00:09.188 *** 2025-05-12 22:00:09.188 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=12076, pid: 22, proc_ser: 72, sid: 177, sess_ser: 40573 ------------------------------------------------------------------------------- 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=9.172211 sec, exc=9.172211 sec, total=9.172211 sec wait times: max=infinite, heur=9.172211 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 40573 --------------------------------------------------- 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 --------------------------------------------------- [10 samples, 22:00:00 - 22:00:09] 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-05-12 22:00:09.188 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12076, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-12 21:56:13.757493 :964205D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-12 21:56:13.757523 :964205D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-12 21:56:13.759342 :964205DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:56:13.759387 :964205DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:56:13.759570 :964205DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:56:13.759798 :964205E0: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-05-12 21:56:13.759833 :964205E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:56:13.764540 :964205E9: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-05-12 21:56:13.764602 :964205EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-12 21:56:13.764633 :964205ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-12 21:56:13.764941 :964205F3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-12 21:56:13.765264 :964205F4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-12 21:56:13.765388 :964205F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:56:13.765619 :964205F6: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-05-12 21:56:13.765644 :964205F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:56:13.767274 :964205FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:56:13.767502 :96420600: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-05-12 21:56:13.767535 :96420603:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:56:13.768418 :96420609: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-05-12 21:56:43.874404 :96420F5E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:56:45.262644 :96421004:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:56:45.249 2025-05-12 21:56:45.262645 :96421005:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8776 pso_num=22 pso_serial#=54 2025-05-12 21:56:45.262777 :96421006:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8776 (legacy spawn) 2025-05-12 21:56:45.269350 :96421007: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-05-12 21:56:45.269528 :9642100C: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-05-12 21:56:45.270336 :96421011:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:56:54.626815 :96421287:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:56:54.619 2025-05-12 21:56:54.626817 :96421288:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13020 pso_num=22 pso_serial#=55 2025-05-12 21:56:54.626950 :96421289:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13020 (legacy spawn) 2025-05-12 21:56:54.633406 :9642128A: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-05-12 21:56:54.633582 :9642128F: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-05-12 21:56:54.634416 :96421294:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:04.208733 :9642155D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:04.194 2025-05-12 21:57:04.208735 :9642155E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12388 pso_num=22 pso_serial#=56 2025-05-12 21:57:04.208868 :9642155F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12388 (legacy spawn) 2025-05-12 21:57:04.215399 :96421560: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-05-12 21:57:04.215588 :96421569: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-05-12 21:57:04.216352 :9642156A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:04.234644 :9642156D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:04.225 2025-05-12 21:57:04.234646 :9642156E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8520 pso_num=22 pso_serial#=57 2025-05-12 21:57:04.234778 :9642156F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8520 (legacy spawn) 2025-05-12 21:57:04.241513 :96421570: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-05-12 21:57:04.241663 :96421579: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-05-12 21:57:04.242612 :9642157A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:07.310986 :9642165B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:07.298 2025-05-12 21:57:07.310988 :9642165C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12452 pso_num=22 pso_serial#=58 2025-05-12 21:57:07.311124 :9642165D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12452 (legacy spawn) 2025-05-12 21:57:07.317577 :9642165E: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-05-12 21:57:07.317753 :96421667: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-05-12 21:57:07.318524 :96421668:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:07.327078 :9642166B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:07.314 2025-05-12 21:57:07.327080 :9642166C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12296 pso_num=22 pso_serial#=59 2025-05-12 21:57:07.327220 :9642166D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12296 (legacy spawn) 2025-05-12 21:57:07.333771 :9642166E: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-05-12 21:57:07.333939 :96421675: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-05-12 21:57:07.334743 :96421678:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:13.756099 :96421857:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:13.750 2025-05-12 21:57:13.756100 :96421858:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11496 pso_num=22 pso_serial#=60 2025-05-12 21:57:13.756235 :96421859:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11496 osp_idx=0 osp_ver=186465 osp_pg=0 (spawn #175812) 2025-05-12 21:57:13.756235 :9642185A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:57:13.756236 :9642185B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=48 time=62843625 2025-05-12 21:57:13.756236 :9642185C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:57:13.756237 :9642185D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:57:13.756237 :9642185E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:57:13.756238 :9642185F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:57:13.756238 :96421860:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:57:13.756712 :96421861: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-05-12 21:57:13.757447 :96421876: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-05-12 21:57:13.765020 :96421892:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:57:13.765461 :96421893: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-05-12 21:57:13.765500 :96421896:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:57:13.766402 :9642189C: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-05-12 21:57:13.766534 :964218A5: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-05-12 21:57:43.834093 :96422231:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:53.562378 :9642253D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:53.553 2025-05-12 21:57:53.562380 :9642253E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2196 pso_num=22 pso_serial#=61 2025-05-12 21:57:53.562516 :9642253F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2196 (legacy spawn) 2025-05-12 21:57:53.569120 :96422540: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-05-12 21:57:53.569293 :96422545: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-05-12 21:57:53.570100 :9642254A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:57:56.790616 :96422651:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:57:56.783 2025-05-12 21:57:56.790618 :96422652:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11668 pso_num=22 pso_serial#=62 2025-05-12 21:57:56.790762 :96422653:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11668 (legacy spawn) 2025-05-12 21:57:56.797304 :96422654: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-05-12 21:57:56.797475 :9642265D: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-05-12 21:57:56.798328 :9642265E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:13.848455 :96422B26:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:13.843 2025-05-12 21:58:13.848457 :96422B27:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12720 pso_num=22 pso_serial#=63 2025-05-12 21:58:13.848591 :96422B28:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12720 osp_idx=0 osp_ver=186468 osp_pg=0 (spawn #175814) 2025-05-12 21:58:13.848591 :96422B29:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:58:13.848592 :96422B2A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=49 time=62903719 2025-05-12 21:58:13.848593 :96422B2B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:58:13.848593 :96422B2C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:58:13.848594 :96422B2D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:58:13.848594 :96422B2E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:58:13.848594 :96422B2F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:58:13.849079 :96422B30: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-05-12 21:58:13.849719 :96422B45: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-05-12 21:58:13.860777 :96422B61:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-12 21:58:13.861230 :96422B62:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-12 21:58:13.863576 :96422B63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:58:13.863925 :96422B64: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-05-12 21:58:13.863961 :96422B67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:58:13.864828 :96422B6D: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-05-12 21:58:13.864948 :96422B76: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-05-12 21:58:43.972003 :964234C3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:51.624981 :9642372B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:51.612 2025-05-12 21:58:51.624982 :9642372C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12692 pso_num=22 pso_serial#=64 2025-05-12 21:58:51.625114 :9642372D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12692 (legacy spawn) 2025-05-12 21:58:51.631574 :9642372E: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-05-12 21:58:51.631745 :96423733: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-05-12 21:58:51.632527 :96423738:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:53.120814 :96423771:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:53.106 2025-05-12 21:58:53.120816 :96423772:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8088 pso_num=22 pso_serial#=65 2025-05-12 21:58:53.120948 :96423773:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8088 (legacy spawn) 2025-05-12 21:58:53.139577 :96423774: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-05-12 21:58:53.139754 :96423779: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-05-12 21:58:53.140605 :9642377E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:53.149298 :96423781:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:53.137 2025-05-12 21:58:53.149300 :96423782:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12308 pso_num=22 pso_serial#=66 2025-05-12 21:58:53.149438 :96423783:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12308 (legacy spawn) 2025-05-12 21:58:53.156018 :96423784: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-05-12 21:58:53.156181 :9642378D: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-05-12 21:58:53.157018 :9642378E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:56.317209 :964238F7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:56.304 2025-05-12 21:58:56.317211 :964238F8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10996 pso_num=22 pso_serial#=67 2025-05-12 21:58:56.317352 :964238F9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10996 (legacy spawn) 2025-05-12 21:58:56.323808 :964238FA: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-05-12 21:58:56.323986 :96423901: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-05-12 21:58:56.324795 :96423904:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:56.333444 :96423907:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:56.319 2025-05-12 21:58:56.333446 :96423908:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12676 pso_num=22 pso_serial#=68 2025-05-12 21:58:56.333587 :96423909:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12676 (legacy spawn) 2025-05-12 21:58:56.340082 :9642390A: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-05-12 21:58:56.340248 :96423912: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-05-12 21:58:56.341058 :96423914:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:58:58.978653 :9642397E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:58:58.970 2025-05-12 21:58:58.978656 :9642397F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12544 pso_num=22 pso_serial#=69 2025-05-12 21:58:58.978788 :96423980:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12544 (legacy spawn) 2025-05-12 21:58:58.985241 :96423981: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-05-12 21:58:58.985417 :9642398A: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-05-12 21:58:58.986280 :9642398B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:59:01.653306 :96423A6B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:59:01.644 2025-05-12 21:59:01.653308 :96423A6C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4728 pso_num=22 pso_serial#=70 2025-05-12 21:59:01.653443 :96423A6D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4728 (legacy spawn) 2025-05-12 21:59:01.659899 :96423A6E: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-05-12 21:59:01.660084 :96423A77: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-05-12 21:59:01.660882 :96423A78:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 21:59:13.929559 :96423E1E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 21:59:13.924 2025-05-12 21:59:13.929561 :96423E1F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11956 pso_num=22 pso_serial#=71 2025-05-12 21:59:13.929698 :96423E20:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11956 osp_idx=0 osp_ver=186476 osp_pg=0 (spawn #175816) 2025-05-12 21:59:13.929699 :96423E21:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 21:59:13.929699 :96423E22:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=50 time=62963797 2025-05-12 21:59:13.929699 :96423E23:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 21:59:13.929700 :96423E24:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 21:59:13.929700 :96423E25:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 21:59:13.929701 :96423E26:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 21:59:13.929701 :96423E27:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 21:59:13.930196 :96423E28: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-05-12 21:59:13.930836 :96423E3D: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-05-12 21:59:13.938379 :96423E5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-12 21:59:13.938749 :96423E5B: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-05-12 21:59:13.938788 :96423E5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-12 21:59:13.939728 :96423E64: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-05-12 21:59:13.939851 :96423E6D: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-05-12 21:59:44.051880 :96424854:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-12 22:00:00.019186 :96424D92:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-12 22:00:00.012 2025-05-12 22:00:00.019188 :96424D93:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12076 pso_num=22 pso_serial#=72 2025-05-12 22:00:00.019296 :96424D94:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12076 osp_idx=0 osp_ver=186477 osp_pg=0 (spawn #175818) 2025-05-12 22:00:00.019297 :96424D95:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 22:00:00.019297 :96424D96:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=51 time=63009891 2025-05-12 22:00:00.019297 :96424D97:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 22:00:00.019298 :96424D98:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 22:00:00.019298 :96424D99:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 22:00:00.019298 :96424D9A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 22:00:00.019299 :96424D9B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 22:00:00.019366 :96424D9C: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-05-12 22:00:00.019372 :96424D9D: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: 12076, VKRM)