Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250526220006\orcl_vkrm_5912_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:37305M/63366M, Ph+PgF:44300M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 5912, image: ORACLE.EXE (VKRM) *** 2025-05-26 22:00:06.678 *** SESSION ID:(193.33469) 2025-05-26 22:00:06.678 *** 2025-05-26 22:00:06.678 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=5912, pid: 24, proc_ser: 102, sid: 193, sess_ser: 33469 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=6.541462 sec, exc=6.541462 sec, total=6.541462 sec wait times: max=infinite, heur=6.541462 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.001078 sec since current wait 0: waited for 'resmgr:internal state change' location=0x3, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.100997 sec, exc=0.100997 sec, total=0.100997 sec wait times: max=0.100000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 33469 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:00 - 22:00:06] 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-26 22:00:06.678 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 5912, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-26 21:56:58.504981 :9C6A1C14:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15156 (legacy spawn) 2025-05-26 21:56:58.511476 :9C6A1C15: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-26 21:56:58.511643 :9C6A1C1D: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-26 21:56:58.512527 :9C6A1C1E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:01.433812 :9C6A1CFE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:01.421 2025-05-26 21:57:01.433814 :9C6A1CFF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10416 pso_num=24 pso_serial#=84 2025-05-26 21:57:01.433960 :9C6A1D00:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10416 (legacy spawn) 2025-05-26 21:57:01.440425 :9C6A1D01: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-26 21:57:01.440599 :9C6A1D06: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-26 21:57:01.441393 :9C6A1D0B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:01.450066 :9C6A1D0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:01.436 2025-05-26 21:57:01.450068 :9C6A1D0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12372 pso_num=24 pso_serial#=85 2025-05-26 21:57:01.450209 :9C6A1D10:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12372 (legacy spawn) 2025-05-26 21:57:01.456667 :9C6A1D11: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-26 21:57:01.456836 :9C6A1D1A: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-26 21:57:01.457642 :9C6A1D1B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:01.993708 :9C6A1D48:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:01.988 2025-05-26 21:57:01.993710 :9C6A1D49:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9764 pso_num=24 pso_serial#=86 2025-05-26 21:57:01.993845 :9C6A1D4A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9764 osp_idx=22 osp_ver=325720 osp_pg=0 (spawn #222472) 2025-05-26 21:57:01.993845 :9C6A1D4B: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-26 21:57:01.993846 :9C6A1D4C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=92 time=1272394250 2025-05-26 21:57:01.993846 :9C6A1D4D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:57:01.993846 :9C6A1D4E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:57:01.993847 :9C6A1D4F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:57:01.993847 :9C6A1D50:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:57:01.993847 :9C6A1D51:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:57:01.994328 :9C6A1D52: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-26 21:57:01.994986 :9C6A1D67: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-26 21:57:02.002460 :9C6A1D83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-26 21:57:02.002835 :9C6A1D84: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-26 21:57:02.002873 :9C6A1D87:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-26 21:57:02.003742 :9C6A1D8D: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-26 21:57:02.003885 :9C6A1D96: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-26 21:57:32.118879 :9C6A2787:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:34.136967 :9C6A27F1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:34.123 2025-05-26 21:57:34.136968 :9C6A27F2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11600 pso_num=24 pso_serial#=87 2025-05-26 21:57:34.137103 :9C6A27F3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11600 (legacy spawn) 2025-05-26 21:57:34.143511 :9C6A27F4: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-26 21:57:34.143692 :9C6A27F9: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-26 21:57:34.144479 :9C6A27FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:34.153146 :9C6A2801:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:34.139 2025-05-26 21:57:34.153148 :9C6A2802:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13424 pso_num=24 pso_serial#=88 2025-05-26 21:57:34.153286 :9C6A2803:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13424 (legacy spawn) 2025-05-26 21:57:34.159625 :9C6A2804: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-26 21:57:34.159788 :9C6A280D: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-26 21:57:34.160557 :9C6A280E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:34.528318 :9C6A2877:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:34.514 2025-05-26 21:57:34.528320 :9C6A2878:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14896 pso_num=24 pso_serial#=89 2025-05-26 21:57:34.528468 :9C6A2879:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14896 (legacy spawn) 2025-05-26 21:57:34.534911 :9C6A287A: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-26 21:57:34.535077 :9C6A2883: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-26 21:57:34.535867 :9C6A2884:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:34.554107 :9C6A2887:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:34.545 2025-05-26 21:57:34.554109 :9C6A2888:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14696 pso_num=24 pso_serial#=90 2025-05-26 21:57:34.554251 :9C6A2889:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14696 (legacy spawn) 2025-05-26 21:57:34.560626 :9C6A288A: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-26 21:57:34.560814 :9C6A2893: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-26 21:57:34.561626 :9C6A2894:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:57:37.055738 :9C6A2931:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:57:37.050 2025-05-26 21:57:37.055741 :9C6A2932:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2472 pso_num=24 pso_serial#=91 2025-05-26 21:57:37.055885 :9C6A2933:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=2472 osp_idx=22 osp_ver=325725 osp_pg=0 (spawn #222474) 2025-05-26 21:57:37.055886 :9C6A2934: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-26 21:57:37.055887 :9C6A2935:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=93 time=1272429313 2025-05-26 21:57:37.055887 :9C6A2936:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:57:37.055888 :9C6A2937:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:57:37.055888 :9C6A2938:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:57:37.055889 :9C6A2939:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:57:37.055889 :9C6A293A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:57:37.056392 :9C6A293B: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-26 21:57:37.057053 :9C6A2950: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-26 21:57:37.062547 :9C6A296C: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-26 21:57:37.063614 :9C6A2974: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-26 21:57:37.063743 :9C6A297D: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-26 21:58:02.084072 :9C6A3162: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-26 21:58:32.168172 :9C6A3B0E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:58:40.391561 :9C6A3D9A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:58:40.386 2025-05-26 21:58:40.391563 :9C6A3D9B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13328 pso_num=24 pso_serial#=92 2025-05-26 21:58:40.391707 :9C6A3D9C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13328 osp_idx=22 osp_ver=325726 osp_pg=0 (spawn #222476) 2025-05-26 21:58:40.391707 :9C6A3D9D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-26 21:58:40.391708 :9C6A3D9E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=94 time=1272492641 2025-05-26 21:58:40.391708 :9C6A3D9F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:58:40.391708 :9C6A3DA0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:58:40.391709 :9C6A3DA1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:58:40.391709 :9C6A3DA2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:58:40.391709 :9C6A3DA3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:58:40.392198 :9C6A3DA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-26 21:58:40.392243 :9C6A3DAA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-26 21:58:40.396288 :9C6A3DD3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-26 21:58:40.396330 :9C6A3DD4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-26 21:58:40.396579 :9C6A3DD5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-26 21:58:40.396594 :9C6A3DD6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-26 21:58:40.396808 :9C6A3DD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-26 21:58:40.396821 :9C6A3DD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-26 21:58:40.397029 :9C6A3DD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-26 21:58:40.397041 :9C6A3DDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-26 21:58:40.397250 :9C6A3DDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-26 21:58:40.397263 :9C6A3DDC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-26 21:58:40.397463 :9C6A3DDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-05-26 21:58:40.397476 :9C6A3DDE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-05-26 21:58:40.399757 :9C6A3DDF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:58:47.256243 :9C6A3FFF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:58:47.241 2025-05-26 21:58:47.256246 :9C6A4000:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13972 pso_num=24 pso_serial#=93 2025-05-26 21:58:47.256383 :9C6A4001:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13972 (legacy spawn) 2025-05-26 21:58:47.262794 :9C6A4002: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-26 21:58:47.262989 :9C6A4009: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-26 21:58:47.273762 :9C6A400C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:58:47.282406 :9C6A400F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:58:47.273 2025-05-26 21:58:47.282408 :9C6A4010:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14340 pso_num=24 pso_serial#=94 2025-05-26 21:58:47.282551 :9C6A4011:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14340 (legacy spawn) 2025-05-26 21:58:47.289001 :9C6A4012: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-26 21:58:47.289163 :9C6A401B: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-26 21:58:47.289973 :9C6A401C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:58:50.374537 :9C6A40FB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:58:50.361 2025-05-26 21:58:50.374539 :9C6A40FC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14192 pso_num=24 pso_serial#=95 2025-05-26 21:58:50.374673 :9C6A40FD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14192 (legacy spawn) 2025-05-26 21:58:50.381137 :9C6A40FE: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-26 21:58:50.381329 :9C6A4106: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-26 21:58:50.382132 :9C6A4108:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:58:50.390786 :9C6A410B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:58:50.377 2025-05-26 21:58:50.390788 :9C6A410C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10316 pso_num=24 pso_serial#=96 2025-05-26 21:58:50.390928 :9C6A410D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10316 (legacy spawn) 2025-05-26 21:58:50.397357 :9C6A410E: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-26 21:58:50.397528 :9C6A4117: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-26 21:58:50.398334 :9C6A4118:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:59:02.193328 :9C6A44B6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:59:02.187 2025-05-26 21:59:02.193330 :9C6A44B7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12624 pso_num=24 pso_serial#=97 2025-05-26 21:59:02.193465 :9C6A44B8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12624 osp_idx=22 osp_ver=325731 osp_pg=0 (spawn #222477) 2025-05-26 21:59:02.193466 :9C6A44B9: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-26 21:59:02.193466 :9C6A44BA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=95 time=1272514454 2025-05-26 21:59:02.193467 :9C6A44BB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 21:59:02.193467 :9C6A44BC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 21:59:02.193467 :9C6A44BD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 21:59:02.193467 :9C6A44BE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 21:59:02.193467 :9C6A44BF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 21:59:02.193949 :9C6A44C0: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-26 21:59:02.194684 :9C6A44D5: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-26 21:59:02.202224 :9C6A44F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-26 21:59:02.202593 :9C6A44F3: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-26 21:59:02.202643 :9C6A44F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-26 21:59:02.203529 :9C6A44FC: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-26 21:59:02.203663 :9C6A4505: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-26 21:59:32.294670 :9C6A4E76:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:59:34.000239 :9C6A4EF3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:59:33.992 2025-05-26 21:59:34.000241 :9C6A4EF4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11704 pso_num=24 pso_serial#=98 2025-05-26 21:59:34.000378 :9C6A4EF5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11704 (legacy spawn) 2025-05-26 21:59:34.006842 :9C6A4EF6: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-26 21:59:34.007016 :9C6A4EFC: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-26 21:59:34.007821 :9C6A4F00:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:59:37.786944 :9C6A5052:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:59:37.778 2025-05-26 21:59:37.786947 :9C6A5053:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12232 pso_num=24 pso_serial#=99 2025-05-26 21:59:37.787084 :9C6A5054:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12232 (legacy spawn) 2025-05-26 21:59:37.793479 :9C6A5055: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-26 21:59:37.793676 :9C6A505A: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-26 21:59:37.794517 :9C6A505F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:59:59.872662 :9C6A574F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:59:59.872 2025-05-26 21:59:59.872663 :9C6A5750:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14636 pso_num=24 pso_serial#=100 2025-05-26 21:59:59.872798 :9C6A5751:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14636 (legacy spawn) 2025-05-26 21:59:59.879219 :9C6A5752: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-26 21:59:59.879395 :9C6A575B: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-26 21:59:59.880188 :9C6A575C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 21:59:59.888946 :9C6A575F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 21:59:59.888 2025-05-26 21:59:59.888948 :9C6A5760:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11620 pso_num=24 pso_serial#=101 2025-05-26 21:59:59.889082 :9C6A5761:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11620 (legacy spawn) 2025-05-26 21:59:59.895494 :9C6A5762: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-26 21:59:59.895652 :9C6A576B: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-26 21:59:59.896428 :9C6A576C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-26 22:00:00.035229 :9C6A5798:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 22:00:00.028 2025-05-26 22:00:00.035231 :9C6A5799:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5912 pso_num=24 pso_serial#=102 2025-05-26 22:00:00.035338 :9C6A579A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=5912 osp_idx=22 osp_ver=325736 osp_pg=0 (spawn #222479) 2025-05-26 22:00:00.035339 :9C6A579B: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-26 22:00:00.035339 :9C6A579C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=96 time=1272572282 2025-05-26 22:00:00.035339 :9C6A579D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-26 22:00:00.035340 :9C6A579E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-26 22:00:00.035340 :9C6A579F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-26 22:00:00.035341 :9C6A57A0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-26 22:00:00.035341 :9C6A57A1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-26 22:00:00.035409 :9C6A57A2: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-26 22:00:00.035415 :9C6A57A3: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: 5912, VKRM)