Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250711220009\orcl_vkrm_1740_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:36430M/63366M, Ph+PgF:42512M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 1740, image: ORACLE.EXE (VKRM) *** 2025-07-11 22:00:09.614 *** SESSION ID:(177.13199) 2025-07-11 22:00:09.614 *** 2025-07-11 22:00:09.614 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=1740, pid: 22, proc_ser: 250, sid: 177, sess_ser: 13199 ------------------------------------------------------------------------------- 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.588808 sec, exc=9.588808 sec, total=9.588808 sec wait times: max=infinite, heur=9.588808 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 13199 --------------------------------------------------- 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-07-11 22:00:09.614 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 1740, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-11 21:57:16.843266 :B0B7781C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:57:16.843266 :B0B7781D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:57:16.843267 :B0B7781E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:57:16.843267 :B0B7781F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:57:16.843267 :B0B77820:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:57:16.843748 :B0B77821: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-07-11 21:57:16.844452 :B0B77836: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-07-11 21:57:16.851847 :B0B77853:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 21:57:16.852198 :B0B77854: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-07-11 21:57:16.852233 :B0B77857:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 21:57:16.853164 :B0B7785D: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-07-11 21:57:16.853284 :B0B77866: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-07-11 21:57:46.931161 :B0B7820F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:57:50.992550 :B0B7835A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:57:50.984 2025-07-11 21:57:50.992552 :B0B7835B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18272 pso_num=22 pso_serial#=234 2025-07-11 21:57:50.992702 :B0B7835C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18272 (legacy spawn) 2025-07-11 21:57:50.999182 :B0B7835D: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-07-11 21:57:50.999353 :B0B78362: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-07-11 21:57:51.000189 :B0B78367:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:57:51.133065 :B0B7837D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:57:51.124 2025-07-11 21:57:51.133067 :B0B7837E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14864 pso_num=22 pso_serial#=235 2025-07-11 21:57:51.133201 :B0B7837F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14864 (legacy spawn) 2025-07-11 21:57:51.139671 :B0B78380: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-07-11 21:57:51.139845 :B0B78389: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-07-11 21:57:51.140698 :B0B7838A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:02.760357 :B0B78739:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:02.755 2025-07-11 21:58:02.760359 :B0B7873A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16852 pso_num=22 pso_serial#=236 2025-07-11 21:58:02.760504 :B0B7873B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16852 osp_idx=0 osp_ver=391223 osp_pg=0 (spawn #375814) 2025-07-11 21:58:02.760505 :B0B7873C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:58:02.760505 :B0B7873D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=179 time=951858658 2025-07-11 21:58:02.760505 :B0B7873E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:58:02.760506 :B0B7873F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:58:02.760506 :B0B78740:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:58:02.760507 :B0B78741:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:58:02.760507 :B0B78742:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:58:02.760998 :B0B78743: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-07-11 21:58:02.761042 :B0B78749: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-07-11 21:58:02.765212 :B0B78758:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-11 21:58:02.765254 :B0B78759:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-11 21:58:02.765514 :B0B7875A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-11 21:58:02.765531 :B0B7875B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-11 21:58:02.765742 :B0B7875C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-11 21:58:02.765755 :B0B7875D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-11 21:58:02.765966 :B0B7875E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-11 21:58:02.765979 :B0B7875F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-11 21:58:02.766184 :B0B78760:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-11 21:58:02.766197 :B0B78761:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-11 21:58:02.766469 :B0B78762:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-11 21:58:02.766481 :B0B78763:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-11 21:58:02.768851 :B0B78764:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:14.543144 :B0B78B03:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:14.541 2025-07-11 21:58:14.543146 :B0B78B04:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1852 pso_num=22 pso_serial#=237 2025-07-11 21:58:14.543288 :B0B78B05:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1852 (legacy spawn) 2025-07-11 21:58:14.549736 :B0B78B06: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-07-11 21:58:14.549914 :B0B78B0B: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-07-11 21:58:14.550703 :B0B78B10:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:14.559409 :B0B78B13:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:14.557 2025-07-11 21:58:14.559411 :B0B78B14:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17708 pso_num=22 pso_serial#=238 2025-07-11 21:58:14.559542 :B0B78B15:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17708 (legacy spawn) 2025-07-11 21:58:14.574000 :B0B78B16: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-07-11 21:58:14.574179 :B0B78B1F: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-07-11 21:58:14.575037 :B0B78B20:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:14.785527 :B0B78B2F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:14.786 2025-07-11 21:58:14.785529 :B0B78B30:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15376 pso_num=22 pso_serial#=239 2025-07-11 21:58:14.785667 :B0B78B31:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15376 (legacy spawn) 2025-07-11 21:58:14.792536 :B0B78B32: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-07-11 21:58:14.792749 :B0B78B3B: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-07-11 21:58:14.793723 :B0B78B3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:14.803113 :B0B78B62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:14.801 2025-07-11 21:58:14.803115 :B0B78B63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15668 pso_num=22 pso_serial#=240 2025-07-11 21:58:14.803260 :B0B78B64:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15668 (legacy spawn) 2025-07-11 21:58:14.809909 :B0B78B65: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-07-11 21:58:14.810085 :B0B78B6E: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-07-11 21:58:14.810966 :B0B78B6F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:16.983265 :B0B78C05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:16.973 2025-07-11 21:58:16.983267 :B0B78C06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16524 pso_num=22 pso_serial#=241 2025-07-11 21:58:16.983403 :B0B78C07:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16524 osp_idx=0 osp_ver=391228 osp_pg=0 (spawn #375815) 2025-07-11 21:58:16.983404 :B0B78C08:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:58:16.983404 :B0B78C09:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=180 time=951872876 2025-07-11 21:58:16.983405 :B0B78C0A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:58:16.983405 :B0B78C0B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:58:16.983406 :B0B78C0C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:58:16.983406 :B0B78C0D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:58:16.983406 :B0B78C0E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:58:16.983908 :B0B78C0F: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-07-11 21:58:16.984550 :B0B78C24: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-07-11 21:58:16.998914 :B0B78C40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 21:58:16.998997 :B0B78C41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 21:58:16.999763 :B0B78C42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 21:58:17.000104 :B0B78C43: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-07-11 21:58:17.000148 :B0B78C46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 21:58:17.008380 :B0B78C4C: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-07-11 21:58:17.008449 :B0B78C4F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-07-11 21:58:17.008484 :B0B78C50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-07-11 21:58:17.008842 :B0B78C56:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 21:58:17.009274 :B0B78C57:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 21:58:17.009483 :B0B78C58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 21:58:17.009744 :B0B78C59: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-07-11 21:58:17.009771 :B0B78C5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 21:58:17.011352 :B0B78C62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 21:58:17.011578 :B0B78C63: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-07-11 21:58:17.011609 :B0B78C66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 21:58:17.012505 :B0B78C6C: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-07-11 21:58:17.012628 :B0B78C75: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-07-11 21:58:47.117739 :B0B7960D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:51.249455 :B0B79764:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:51.241 2025-07-11 21:58:51.249457 :B0B79765:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10908 pso_num=22 pso_serial#=242 2025-07-11 21:58:51.249639 :B0B79766:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10908 (legacy spawn) 2025-07-11 21:58:51.256065 :B0B79767: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-07-11 21:58:51.256241 :B0B79770: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-07-11 21:58:51.257097 :B0B79771:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:51.265849 :B0B79774:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:51.257 2025-07-11 21:58:51.265851 :B0B79775:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1312 pso_num=22 pso_serial#=243 2025-07-11 21:58:51.265997 :B0B79776:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1312 (legacy spawn) 2025-07-11 21:58:51.272619 :B0B79777: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-07-11 21:58:51.272799 :B0B79780: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-07-11 21:58:51.273702 :B0B79781:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:53.203201 :B0B7982C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:53.194 2025-07-11 21:58:53.203203 :B0B7982D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2512 pso_num=22 pso_serial#=244 2025-07-11 21:58:53.203337 :B0B7982E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2512 (legacy spawn) 2025-07-11 21:58:53.209816 :B0B7982F: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-07-11 21:58:53.209986 :B0B79834: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-07-11 21:58:53.210844 :B0B79839:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:54.312386 :B0B7987E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:54.298 2025-07-11 21:58:54.312388 :B0B7987F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16692 pso_num=22 pso_serial#=245 2025-07-11 21:58:54.312527 :B0B79880:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16692 (legacy spawn) 2025-07-11 21:58:54.334192 :B0B79881: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-07-11 21:58:54.334368 :B0B7988A: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-07-11 21:58:54.335157 :B0B7988B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:54.343721 :B0B7988E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:54.330 2025-07-11 21:58:54.343723 :B0B7988F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16392 pso_num=22 pso_serial#=246 2025-07-11 21:58:54.343862 :B0B79890:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16392 (legacy spawn) 2025-07-11 21:58:54.350359 :B0B79891: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-07-11 21:58:54.350524 :B0B7989A: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-07-11 21:58:54.351328 :B0B7989B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:58:59.422124 :B0B79A3C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:58:59.412 2025-07-11 21:58:59.422127 :B0B79A3D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13548 pso_num=22 pso_serial#=247 2025-07-11 21:58:59.422270 :B0B79A3E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13548 (legacy spawn) 2025-07-11 21:58:59.429080 :B0B79A3F: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-07-11 21:58:59.429252 :B0B79A44: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-07-11 21:58:59.430128 :B0B79A49:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:59:17.050985 :B0B79F99:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:59:17.045 2025-07-11 21:59:17.050987 :B0B79F9A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12204 pso_num=22 pso_serial#=248 2025-07-11 21:59:17.051121 :B0B79F9B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12204 osp_idx=0 osp_ver=391235 osp_pg=0 (spawn #375817) 2025-07-11 21:59:17.051122 :B0B79F9C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:59:17.051122 :B0B79F9D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=181 time=951932939 2025-07-11 21:59:17.051122 :B0B79F9E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:59:17.051123 :B0B79F9F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:59:17.051123 :B0B79FA0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:59:17.051123 :B0B79FA1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:59:17.051124 :B0B79FA2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:59:17.051605 :B0B79FA3: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-07-11 21:59:17.052242 :B0B79FB8: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-07-11 21:59:17.059710 :B0B79FD4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 21:59:17.060075 :B0B79FD5: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-07-11 21:59:17.060111 :B0B79FD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 21:59:17.061063 :B0B79FDE: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-07-11 21:59:17.061185 :B0B79FE7: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-07-11 21:59:47.152807 :B0B7A98F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 21:59:55.407569 :B0B7AC19:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 21:59:55.399 2025-07-11 21:59:55.407571 :B0B7AC1A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16960 pso_num=22 pso_serial#=249 2025-07-11 21:59:55.407708 :B0B7AC1B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16960 (legacy spawn) 2025-07-11 21:59:55.414197 :B0B7AC1C: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-07-11 21:59:55.414372 :B0B7AC21: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-07-11 21:59:55.415168 :B0B7AC54:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 22:00:00.030410 :B0B7ADA6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 22:00:00.023 2025-07-11 22:00:00.030411 :B0B7ADA7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1740 pso_num=22 pso_serial#=250 2025-07-11 22:00:00.030519 :B0B7ADA8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1740 osp_idx=0 osp_ver=391237 osp_pg=0 (spawn #375819) 2025-07-11 22:00:00.030520 :B0B7ADA9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 22:00:00.030520 :B0B7ADAA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=182 time=951975923 2025-07-11 22:00:00.030521 :B0B7ADAB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 22:00:00.030521 :B0B7ADAC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 22:00:00.030521 :B0B7ADAD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 22:00:00.030522 :B0B7ADAE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 22:00:00.030522 :B0B7ADAF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 22:00:00.030594 :B0B7ADB0: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-07-11 22:00:00.030600 :B0B7ADB1: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: 1740, VKRM)