Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250701220008\orcl_vkrm_13880_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:36782M/63366M, Ph+PgF:42838M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 13880, image: ORACLE.EXE (VKRM) *** 2025-07-01 22:00:08.265 *** SESSION ID:(177.11359) 2025-07-01 22:00:08.265 *** 2025-07-01 22:00:08.265 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=13880, pid: 22, proc_ser: 2, sid: 177, sess_ser: 11359 ------------------------------------------------------------------------------- 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=8.238854 sec, exc=8.238854 sec, total=8.238854 sec wait times: max=infinite, heur=8.238854 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 11359 --------------------------------------------------- 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-07-01 22:00:08.265 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 13880, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-01 21:56:53.842426 :AC4204B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-01 21:56:53.842472 :AC4204BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-01 21:56:53.842733 :AC4204BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-01 21:56:53.842749 :AC4204BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-01 21:56:53.842965 :AC4204BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-01 21:56:53.842978 :AC4204BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-01 21:56:53.843181 :AC4204BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-01 21:56:53.843194 :AC4204C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-01 21:56:53.843401 :AC4204C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-01 21:56:53.843414 :AC4204C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-01 21:56:53.843611 :AC4204C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-01 21:56:53.843624 :AC4204C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-01 21:56:53.846044 :AC4204C5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:00.695188 :AC4206DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:00.687 2025-07-01 21:57:00.695190 :AC4206DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17720 pso_num=22 pso_serial#=238 2025-07-01 21:57:00.695330 :AC4206DC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17720 (legacy spawn) 2025-07-01 21:57:00.701726 :AC4206DD: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-01 21:57:00.701904 :AC4206E2: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-01 21:57:00.702744 :AC4206E7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:07.393045 :AC4208CC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:07.379 2025-07-01 21:57:07.393047 :AC4208CD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11036 pso_num=22 pso_serial#=239 2025-07-01 21:57:07.393183 :AC4208CE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11036 (legacy spawn) 2025-07-01 21:57:07.399523 :AC4208CF: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-01 21:57:07.399695 :AC4208D4: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-01 21:57:07.400471 :AC4208D9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:07.409112 :AC4208DC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:07.395 2025-07-01 21:57:07.409114 :AC4208DD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13448 pso_num=22 pso_serial#=240 2025-07-01 21:57:07.409248 :AC4208DE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13448 (legacy spawn) 2025-07-01 21:57:07.415667 :AC4208DF: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-01 21:57:07.415829 :AC4208E8: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-01 21:57:07.416664 :AC4208E9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:09.533365 :AC4209CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:09.524 2025-07-01 21:57:09.533367 :AC4209CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13440 pso_num=22 pso_serial#=241 2025-07-01 21:57:09.533505 :AC4209CC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13440 (legacy spawn) 2025-07-01 21:57:09.539941 :AC4209CD: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-01 21:57:09.540111 :AC4209D2: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-01 21:57:09.540886 :AC4209D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:11.006278 :AC420A5E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:10.993 2025-07-01 21:57:11.006280 :AC420A5F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4372 pso_num=22 pso_serial#=242 2025-07-01 21:57:11.006418 :AC420A60:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4372 (legacy spawn) 2025-07-01 21:57:11.012867 :AC420A61: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-01 21:57:11.013041 :AC420A6A: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-01 21:57:11.013808 :AC420A6B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:11.022517 :AC420A6E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:11.008 2025-07-01 21:57:11.022519 :AC420A6F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12508 pso_num=22 pso_serial#=243 2025-07-01 21:57:11.022655 :AC420A70:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12508 (legacy spawn) 2025-07-01 21:57:11.029122 :AC420A71: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-01 21:57:11.029288 :AC420A7A: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-01 21:57:11.030065 :AC420A7B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:15.226053 :AC420BA4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:15.221 2025-07-01 21:57:15.226055 :AC420BA5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18196 pso_num=22 pso_serial#=244 2025-07-01 21:57:15.226190 :AC420BA6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18196 osp_idx=0 osp_ver=362671 osp_pg=0 (spawn #342491) 2025-07-01 21:57:15.226191 :AC420BA7: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-01 21:57:15.226191 :AC420BA8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=184 time=87811126 2025-07-01 21:57:15.226191 :AC420BA9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 21:57:15.226191 :AC420BAA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 21:57:15.226192 :AC420BAB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 21:57:15.226192 :AC420BAC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 21:57:15.226192 :AC420BAD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 21:57:15.226701 :AC420BAE: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-01 21:57:15.227386 :AC420BC3: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-01 21:57:15.234824 :AC420BE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-01 21:57:15.235245 :AC420BE1: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-01 21:57:15.235281 :AC420BE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-01 21:57:15.236166 :AC420BEA: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-01 21:57:15.236290 :AC420BF3: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-01 21:57:45.320844 :AC42152E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:47.216585 :AC4215DE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:47.203 2025-07-01 21:57:47.216587 :AC4215DF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8032 pso_num=22 pso_serial#=245 2025-07-01 21:57:47.216724 :AC4215E0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8032 (legacy spawn) 2025-07-01 21:57:47.223270 :AC4215E1: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-01 21:57:47.223442 :AC4215EA: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-01 21:57:47.224244 :AC4215EB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:57:47.232875 :AC4215EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:57:47.219 2025-07-01 21:57:47.232878 :AC4215EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4640 pso_num=22 pso_serial#=246 2025-07-01 21:57:47.233014 :AC4215F0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4640 (legacy spawn) 2025-07-01 21:57:47.252058 :AC4215F1: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-01 21:57:47.252226 :AC4215FA: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-01 21:57:47.253070 :AC4215FB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:02.932494 :AC421A9F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:02.924 2025-07-01 21:58:02.932496 :AC421AA0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6020 pso_num=22 pso_serial#=247 2025-07-01 21:58:02.932632 :AC421AA1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6020 (legacy spawn) 2025-07-01 21:58:02.939094 :AC421AA2: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-01 21:58:02.939271 :AC421AA7: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-01 21:58:02.940084 :AC421AAC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:15.339667 :AC421E67:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:15.334 2025-07-01 21:58:15.339669 :AC421E68:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4120 pso_num=22 pso_serial#=248 2025-07-01 21:58:15.339805 :AC421E69:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4120 osp_idx=0 osp_ver=362675 osp_pg=0 (spawn #342493) 2025-07-01 21:58:15.339806 :AC421E6A: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-01 21:58:15.339806 :AC421E6B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=185 time=87871236 2025-07-01 21:58:15.339806 :AC421E6C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 21:58:15.339807 :AC421E6D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 21:58:15.339807 :AC421E6E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 21:58:15.339807 :AC421E6F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 21:58:15.339808 :AC421E70:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 21:58:15.340288 :AC421E71: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-01 21:58:15.340992 :AC421E86: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-01 21:58:15.352007 :AC421EA2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-01 21:58:15.352451 :AC421EA3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-01 21:58:15.354827 :AC421EA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-01 21:58:15.355172 :AC421EA5: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-01 21:58:15.355207 :AC421EA8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-01 21:58:15.356094 :AC421EAE: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-01 21:58:15.356221 :AC421EB7: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-01 21:58:45.438114 :AC422801:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:51.862246 :AC422A03:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:51.853 2025-07-01 21:58:51.862248 :AC422A04:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11428 pso_num=22 pso_serial#=249 2025-07-01 21:58:51.862390 :AC422A05:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11428 (legacy spawn) 2025-07-01 21:58:51.886429 :AC422A06: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-01 21:58:51.886599 :AC422A0B: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-01 21:58:51.887461 :AC422A10:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:56.609868 :AC422B85:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:56.595 2025-07-01 21:58:56.609871 :AC422B86:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1220 pso_num=22 pso_serial#=250 2025-07-01 21:58:56.610005 :AC422B87:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1220 (legacy spawn) 2025-07-01 21:58:56.616620 :AC422B88: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-01 21:58:56.616813 :AC422B91: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-01 21:58:56.617652 :AC422B92:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:56.635423 :AC422B95:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:56.626 2025-07-01 21:58:56.635425 :AC422B96:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17352 pso_num=22 pso_serial#=251 2025-07-01 21:58:56.635568 :AC422B97:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17352 (legacy spawn) 2025-07-01 21:58:56.642443 :AC422B98: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-01 21:58:56.642628 :AC422BA0: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-01 21:58:56.643534 :AC422BA1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:59.701962 :AC422C7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:59.689 2025-07-01 21:58:59.701964 :AC422C80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16116 pso_num=22 pso_serial#=252 2025-07-01 21:58:59.702108 :AC422C81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16116 (legacy spawn) 2025-07-01 21:58:59.708628 :AC422C82: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-01 21:58:59.708801 :AC422C8B: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-01 21:58:59.709594 :AC422C8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:58:59.718175 :AC422C8F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:58:59.705 2025-07-01 21:58:59.718177 :AC422C90:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11176 pso_num=22 pso_serial#=253 2025-07-01 21:58:59.718311 :AC422C91:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11176 (legacy spawn) 2025-07-01 21:58:59.724750 :AC422C92: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-01 21:58:59.724911 :AC422C9B: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-01 21:58:59.725753 :AC422C9C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:59:05.107093 :AC422E5B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:59:05.098 2025-07-01 21:59:05.107095 :AC422E5C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18376 pso_num=22 pso_serial#=254 2025-07-01 21:59:05.107239 :AC422E5D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18376 (legacy spawn) 2025-07-01 21:59:05.113693 :AC422E5E: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-01 21:59:05.152140 :AC422E67: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-01 21:59:05.153080 :AC422E68:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:59:15.417275 :AC42315F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:59:15.410 2025-07-01 21:59:15.417278 :AC423160:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1852 pso_num=22 pso_serial#=255 2025-07-01 21:59:15.417441 :AC423161:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1852 osp_idx=0 osp_ver=362682 osp_pg=0 (spawn #342495) 2025-07-01 21:59:15.417442 :AC423162: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-01 21:59:15.417443 :AC423163:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=186 time=87931314 2025-07-01 21:59:15.417444 :AC423164:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 21:59:15.417444 :AC423165:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 21:59:15.417445 :AC423166:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 21:59:15.417445 :AC423167:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 21:59:15.417446 :AC423168:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 21:59:15.418038 :AC423169: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-01 21:59:15.418840 :AC42317E: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-01 21:59:15.427544 :AC42319A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-01 21:59:15.427998 :AC42319B: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-01 21:59:15.428047 :AC42319E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-01 21:59:15.429110 :AC4231A4: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-01 21:59:15.429255 :AC4231AD: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-01 21:59:45.484619 :AC423BA3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 21:59:59.862886 :AC42401C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 21:59:59.851 2025-07-01 21:59:59.862888 :AC42401D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16200 pso_num=22 pso_serial#=1 2025-07-01 21:59:59.863024 :AC42401E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16200 (legacy spawn) 2025-07-01 21:59:59.869950 :AC42401F: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-01 21:59:59.870138 :AC424028: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-01 21:59:59.871049 :AC424029:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-01 22:00:00.030159 :AC424049:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-01 22:00:00.023 2025-07-01 22:00:00.030161 :AC42404A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13880 pso_num=22 pso_serial#=2 2025-07-01 22:00:00.030267 :AC42404B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13880 osp_idx=0 osp_ver=362684 osp_pg=0 (spawn #342497) 2025-07-01 22:00:00.030267 :AC42404C: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-01 22:00:00.030268 :AC42404D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=187 time=87975923 2025-07-01 22:00:00.030268 :AC42404E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 22:00:00.030268 :AC42404F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 22:00:00.030269 :AC424050:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 22:00:00.030270 :AC424051:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 22:00:00.030270 :AC424052:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 22:00:00.030336 :AC424053: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-01 22:00:00.030342 :AC424054: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: 13880, VKRM)