Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250611220008\orcl_vkrm_4800_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:36742M/63366M, Ph+PgF:43286M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 4800, image: ORACLE.EXE (VKRM) *** 2025-06-11 22:00:08.575 *** SESSION ID:(177.55621) 2025-06-11 22:00:08.575 *** 2025-06-11 22:00:08.575 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=4800, pid: 22, proc_ser: 244, sid: 177, sess_ser: 55621 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=3 snap_id=2 wait times: snap=8.440695 sec, exc=8.440697 sec, total=8.541154 sec wait times: max=infinite, heur=8.541154 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x15a0 Wait State: fixed_waits=0 flags=0x23 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000000 sec since current wait 0: waited for 'resmgr:internal state change' location=0x3, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.100457 sec, exc=0.100457 sec, total=0.100457 sec wait times: max=0.100000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time 1: waited for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000000 sec of elapsed time Sampled Session History of session 177 serial 55621 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:00:00 - 22:00:08] 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-06-11 22:00:08.575 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 4800, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-11 21:57:30.329141 :A36D0102:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:33.622411 :A36D01EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:33.610 2025-06-11 21:57:33.622413 :A36D01EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11704 pso_num=22 pso_serial#=224 2025-06-11 21:57:33.622546 :A36D01F0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11704 (legacy spawn) 2025-06-11 21:57:33.628920 :A36D01F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:57:33.629094 :A36D01F6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:57:33.629897 :A36D01FB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:33.638672 :A36D01FE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:33.625 2025-06-11 21:57:33.638674 :A36D01FF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14508 pso_num=22 pso_serial#=225 2025-06-11 21:57:33.638811 :A36D0200:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14508 (legacy spawn) 2025-06-11 21:57:33.645313 :A36D0201:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:57:33.645472 :A36D020A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:57:33.646245 :A36D020B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:36.690676 :A36D02FE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:36.677 2025-06-11 21:57:36.690678 :A36D02FF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17384 pso_num=22 pso_serial#=226 2025-06-11 21:57:36.690816 :A36D0300:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17384 (legacy spawn) 2025-06-11 21:57:36.697300 :A36D0301:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:57:36.697485 :A36D0306:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:57:36.698307 :A36D030B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:36.706850 :A36D030E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:36.693 2025-06-11 21:57:36.706852 :A36D030F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17220 pso_num=22 pso_serial#=227 2025-06-11 21:57:36.707001 :A36D0310:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17220 (legacy spawn) 2025-06-11 21:57:36.713403 :A36D0311:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:57:36.713578 :A36D031A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:57:36.714363 :A36D031B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:43.483834 :A36D0590:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:43.478 2025-06-11 21:57:43.483837 :A36D0591:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17056 pso_num=22 pso_serial#=228 2025-06-11 21:57:43.483984 :A36D0592:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17056 osp_idx=0 osp_ver=298140 osp_pg=0 (spawn #275802) 2025-06-11 21:57:43.483985 :A36D0593:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 21:57:43.483985 :A36D0594:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=2654806672 2025-06-11 21:57:43.483986 :A36D0595:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 21:57:43.483986 :A36D0596:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 21:57:43.483987 :A36D0597:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 21:57:43.483987 :A36D0598:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 21:57:43.483987 :A36D0599:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 21:57:43.484500 :A36D059A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 21:57:43.484549 :A36D05A0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-11 21:57:43.488648 :A36D05C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-11 21:57:43.488692 :A36D05CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-11 21:57:43.488964 :A36D05CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-11 21:57:43.488981 :A36D05CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-11 21:57:43.489197 :A36D05CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-11 21:57:43.489211 :A36D05CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-11 21:57:43.489422 :A36D05CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-11 21:57:43.489434 :A36D05D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-11 21:57:43.489641 :A36D05D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-11 21:57:43.489653 :A36D05D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-11 21:57:43.489855 :A36D05D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-06-11 21:57:43.489868 :A36D05D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-06-11 21:57:43.492285 :A36D05D5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:46.453060 :A36D068A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:46.445 2025-06-11 21:57:46.453062 :A36D068B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12820 pso_num=22 pso_serial#=229 2025-06-11 21:57:46.453193 :A36D068C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12820 (legacy spawn) 2025-06-11 21:57:46.459740 :A36D068D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:57:46.459915 :A36D0692:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:57:46.460756 :A36D06A5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:57:48.423356 :A36D071B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:57:48.414 2025-06-11 21:57:48.423358 :A36D071C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4856 pso_num=22 pso_serial#=230 2025-06-11 21:57:48.423485 :A36D071D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4856 (legacy spawn) 2025-06-11 21:57:48.429893 :A36D071E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:57:48.430059 :A36D0723:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:57:48.430815 :A36D0728:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:00.313863 :A36D0AD6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:00.308 2025-06-11 21:58:00.313865 :A36D0AD7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12380 pso_num=22 pso_serial#=231 2025-06-11 21:58:00.314006 :A36D0AD8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12380 osp_idx=0 osp_ver=298143 osp_pg=0 (spawn #275803) 2025-06-11 21:58:00.314007 :A36D0AD9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 21:58:00.314007 :A36D0ADA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=2654823500 2025-06-11 21:58:00.314007 :A36D0ADB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 21:58:00.314008 :A36D0ADC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 21:58:00.314008 :A36D0ADD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 21:58:00.314010 :A36D0ADE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 21:58:00.314010 :A36D0ADF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 21:58:00.314503 :A36D0AE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 21:58:00.315155 :A36D0AF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:00.326167 :A36D0B12:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-11 21:58:00.326627 :A36D0B13:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-11 21:58:00.329000 :A36D0B14:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-11 21:58:00.329338 :A36D0B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:00.329371 :A36D0B18:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-11 21:58:00.330251 :A36D0B1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:00.330371 :A36D0B27:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:30.400575 :A36D14B4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:46.593218 :A36D19C0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:46.583 2025-06-11 21:58:46.593220 :A36D19C1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16036 pso_num=22 pso_serial#=232 2025-06-11 21:58:46.593354 :A36D19C2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16036 (legacy spawn) 2025-06-11 21:58:46.599797 :A36D19C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:46.599968 :A36D19CC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:46.600778 :A36D19CD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:46.609959 :A36D19F3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:46.609 2025-06-11 21:58:46.609961 :A36D19F4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17152 pso_num=22 pso_serial#=233 2025-06-11 21:58:46.610102 :A36D19F5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17152 (legacy spawn) 2025-06-11 21:58:46.616461 :A36D19F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:46.616620 :A36D19FF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:46.617383 :A36D1A00:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:48.611936 :A36D1A53:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:48.603 2025-06-11 21:58:48.611939 :A36D1A54:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10660 pso_num=22 pso_serial#=234 2025-06-11 21:58:48.612076 :A36D1A55:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10660 (legacy spawn) 2025-06-11 21:58:48.618523 :A36D1A56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:48.618690 :A36D1A5B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:48.619547 :A36D1A60:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:49.736226 :A36D1AF3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:49.723 2025-06-11 21:58:49.736228 :A36D1AF4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16068 pso_num=22 pso_serial#=235 2025-06-11 21:58:49.736370 :A36D1AF5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16068 (legacy spawn) 2025-06-11 21:58:49.758915 :A36D1AF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:49.759088 :A36D1AFF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:49.759894 :A36D1B00:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:49.768535 :A36D1B03:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:49.754 2025-06-11 21:58:49.768538 :A36D1B04:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13328 pso_num=22 pso_serial#=236 2025-06-11 21:58:49.768692 :A36D1B05:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13328 (legacy spawn) 2025-06-11 21:58:49.775105 :A36D1B06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:49.775265 :A36D1B0F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:49.776098 :A36D1B10:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:56.333417 :A36D1CF8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:56.325 2025-06-11 21:58:56.333419 :A36D1CF9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15396 pso_num=22 pso_serial#=237 2025-06-11 21:58:56.333550 :A36D1CFA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15396 (legacy spawn) 2025-06-11 21:58:56.339959 :A36D1CFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:56.340137 :A36D1D04:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:56.340925 :A36D1D05:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:57.447564 :A36D1D4E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:57.435 2025-06-11 21:58:57.447566 :A36D1D4F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13876 pso_num=22 pso_serial#=238 2025-06-11 21:58:57.447702 :A36D1D50:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13876 (legacy spawn) 2025-06-11 21:58:57.454093 :A36D1D51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:57.454265 :A36D1D57:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:57.455061 :A36D1D5B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:58:57.463654 :A36D1D5E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:58:57.450 2025-06-11 21:58:57.463656 :A36D1D5F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17236 pso_num=22 pso_serial#=239 2025-06-11 21:58:57.463797 :A36D1D60:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17236 (legacy spawn) 2025-06-11 21:58:57.470304 :A36D1D61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:58:57.470464 :A36D1D6A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:58:57.471278 :A36D1D6B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:59:00.394960 :A36D1E44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:59:00.389 2025-06-11 21:59:00.394962 :A36D1E45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13420 pso_num=22 pso_serial#=240 2025-06-11 21:59:00.395098 :A36D1E46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13420 osp_idx=0 osp_ver=298152 osp_pg=0 (spawn #275805) 2025-06-11 21:59:00.395099 :A36D1E47:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 21:59:00.395099 :A36D1E48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=226 time=2654883579 2025-06-11 21:59:00.395099 :A36D1E49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 21:59:00.395100 :A36D1E4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 21:59:00.395100 :A36D1E4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 21:59:00.395100 :A36D1E4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 21:59:00.395101 :A36D1E4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 21:59:00.395578 :A36D1E4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-11 21:59:00.396293 :A36D1E63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:59:00.403750 :A36D1E7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-11 21:59:00.404871 :A36D1E80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:59:00.404902 :A36D1E83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-11 21:59:00.405795 :A36D1E89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:59:00.405916 :A36D1E92:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:59:30.487557 :A36D2836:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:59:50.764458 :A36D2E90:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:59:50.756 2025-06-11 21:59:50.764460 :A36D2E91:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16944 pso_num=22 pso_serial#=241 2025-06-11 21:59:50.764599 :A36D2E92:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16944 (legacy spawn) 2025-06-11 21:59:50.771005 :A36D2E93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:59:50.771192 :A36D2E98:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:59:50.772016 :A36D2E9D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:59:59.498099 :A36D313E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:59:59.484 2025-06-11 21:59:59.498101 :A36D313F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16292 pso_num=22 pso_serial#=242 2025-06-11 21:59:59.498237 :A36D3140:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16292 (legacy spawn) 2025-06-11 21:59:59.504640 :A36D3141:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:59:59.504817 :A36D314A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:59:59.505624 :A36D314B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 21:59:59.514276 :A36D314E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 21:59:59.500 2025-06-11 21:59:59.514278 :A36D314F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16532 pso_num=22 pso_serial#=243 2025-06-11 21:59:59.514415 :A36D3150:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16532 (legacy spawn) 2025-06-11 21:59:59.520801 :A36D3151:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 21:59:59.520959 :A36D315A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-11 21:59:59.521730 :A36D315B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-11 22:00:00.037483 :A36D3186:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-11 22:00:00.031 2025-06-11 22:00:00.037485 :A36D3187:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4800 pso_num=22 pso_serial#=244 2025-06-11 22:00:00.037582 :A36D3188:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4800 osp_idx=0 osp_ver=298156 osp_pg=0 (spawn #275807) 2025-06-11 22:00:00.037583 :A36D3189:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:00:00.037584 :A36D318A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=227 time=2654943219 2025-06-11 22:00:00.037584 :A36D318B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:00:00.037584 :A36D318C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:00:00.037585 :A36D318D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:00:00.037585 :A36D318E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:00:00.037585 :A36D318F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:00:00.037650 :A36D3190: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-06-11 22:00:00.037655 :A36D3191: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: 4800, VKRM)