Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250515220005\orcl_vkrm_9660_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:37398M/63366M, Ph+PgF:44648M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 9660, image: ORACLE.EXE (VKRM) *** 2025-05-15 22:00:05.476 *** SESSION ID:(177.2215) 2025-05-15 22:00:05.476 *** 2025-05-15 22:00:05.476 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=9660, pid: 22, proc_ser: 208, sid: 177, sess_ser: 2215 ------------------------------------------------------------------------------- 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=5.448772 sec, exc=5.448772 sec, total=5.448772 sec wait times: max=infinite, heur=5.448772 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 2215 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:00:00 - 22:00:05] 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-15 22:00:05.476 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 9660, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-15 21:57:00.466632 :9791EBA6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12380 osp_idx=0 osp_ver=198068 osp_pg=0 (spawn #185813) 2025-05-15 21:57:00.466633 :9791EBA7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-15 21:57:00.466634 :9791EBA8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=194 time=322030329 2025-05-15 21:57:00.466634 :9791EBA9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 21:57:00.466634 :9791EBAA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 21:57:00.466635 :9791EBAB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 21:57:00.466635 :9791EBAC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 21:57:00.466635 :9791EBAD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 21:57:00.467141 :9791EBAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-15 21:57:00.467777 :9791EBC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:00.475305 :9791EBDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-15 21:57:00.475661 :9791EBE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:00.475696 :9791EBE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-15 21:57:00.476536 :9791EBE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:00.476658 :9791EBF2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:57:30.551128 :9791F630:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:57:39.953356 :9791F90F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:57:39.945 2025-05-15 21:57:39.953358 :9791F910:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11704 pso_num=22 pso_serial#=189 2025-05-15 21:57:39.953513 :9791F911:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11704 (legacy spawn) 2025-05-15 21:57:39.960541 :9791F912:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:39.960711 :9791F917:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:57:39.961582 :9791F91C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:57:55.310294 :9791FDD0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:57:55.307 2025-05-15 21:57:55.310296 :9791FDD1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13160 pso_num=22 pso_serial#=190 2025-05-15 21:57:55.310432 :9791FDD2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13160 (legacy spawn) 2025-05-15 21:57:55.316860 :9791FDD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:55.317051 :9791FDD8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:57:55.317858 :9791FDDD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:57:55.326523 :9791FDE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:57:55.323 2025-05-15 21:57:55.326525 :9791FDE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10660 pso_num=22 pso_serial#=191 2025-05-15 21:57:55.326667 :9791FDE2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10660 (legacy spawn) 2025-05-15 21:57:55.333104 :9791FDE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:55.333267 :9791FDEC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:57:55.334061 :9791FDED:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:57:58.528566 :9791FEE8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:57:58.515 2025-05-15 21:57:58.528569 :9791FEE9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12940 pso_num=22 pso_serial#=192 2025-05-15 21:57:58.528705 :9791FEEA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12940 (legacy spawn) 2025-05-15 21:57:58.884067 :9791FEEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:58.884250 :9791FEF0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:57:58.885113 :9791FEF5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:57:58.894586 :9791FEF8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:57:58.890 2025-05-15 21:57:58.894588 :9791FEF9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11428 pso_num=22 pso_serial#=193 2025-05-15 21:57:58.894726 :9791FEFA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11428 (legacy spawn) 2025-05-15 21:57:58.901547 :9791FEFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:57:58.901736 :9791FF04:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:57:58.902663 :9791FF05:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:00.552350 :9791FFF1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:00.546 2025-05-15 21:58:00.552352 :9791FFF2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11336 pso_num=22 pso_serial#=194 2025-05-15 21:58:00.552493 :9791FFF3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11336 osp_idx=0 osp_ver=198074 osp_pg=0 (spawn #185816) 2025-05-15 21:58:00.552493 :9791FFF4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-15 21:58:00.552494 :9791FFF5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=322090422 2025-05-15 21:58:00.552494 :9791FFF6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 21:58:00.552495 :9791FFF7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 21:58:00.552495 :9791FFF8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 21:58:00.552496 :9791FFF9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 21:58:00.552496 :9791FFFA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 21:58:00.552999 :9791FFFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.553648 :97920010:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.567784 :9792002D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-15 21:58:00.567868 :9792002E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-15 21:58:00.568717 :9792002F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-15 21:58:00.569055 :97920030:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.569097 :97920033:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-15 21:58:00.577089 :97920039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.577164 :9792003C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-15 21:58:00.577201 :9792003D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-15 21:58:00.577557 :97920043:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-15 21:58:00.577951 :97920044:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-15 21:58:00.578160 :97920045:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-15 21:58:00.578411 :97920046:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.578437 :97920049:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-15 21:58:00.580026 :9792004F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-15 21:58:00.580283 :97920050:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.580311 :97920053:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-15 21:58:00.581311 :97920059:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:00.581424 :97920062:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:30.692105 :97920A11:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:32.091238 :97920A47:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:32.077 2025-05-15 21:58:32.091240 :97920A48:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4208 pso_num=22 pso_serial#=195 2025-05-15 21:58:32.091370 :97920A49:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4208 (legacy spawn) 2025-05-15 21:58:32.109677 :97920A4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:32.109857 :97920A4F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:32.110724 :97920A54:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:32.119500 :97920A57:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:32.108 2025-05-15 21:58:32.119502 :97920A58:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10216 pso_num=22 pso_serial#=196 2025-05-15 21:58:32.119640 :97920A59:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10216 (legacy spawn) 2025-05-15 21:58:32.126135 :97920A65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:32.126305 :97920A6E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:32.127102 :97920A7D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:35.178189 :97920B77:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:35.165 2025-05-15 21:58:35.178191 :97920B78:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12232 pso_num=22 pso_serial#=197 2025-05-15 21:58:35.178326 :97920B79:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12232 (legacy spawn) 2025-05-15 21:58:35.184770 :97920B7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:35.184944 :97920B83:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:35.185785 :97920B84:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:35.194355 :97920B87:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:35.181 2025-05-15 21:58:35.194357 :97920B88:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3236 pso_num=22 pso_serial#=198 2025-05-15 21:58:35.194546 :97920B89:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=3236 (legacy spawn) 2025-05-15 21:58:35.200908 :97920B8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:35.201091 :97920B93:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:35.201905 :97920B94:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:36.857924 :97920C3D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:36.850 2025-05-15 21:58:36.857926 :97920C3E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11940 pso_num=22 pso_serial#=199 2025-05-15 21:58:36.858058 :97920C3F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11940 (legacy spawn) 2025-05-15 21:58:36.864548 :97920C40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:36.864715 :97920C45:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:36.865534 :97920C4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:58:42.182310 :97920DDE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:58:42.174 2025-05-15 21:58:42.182312 :97920DDF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10836 pso_num=22 pso_serial#=200 2025-05-15 21:58:42.182445 :97920DE0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10836 (legacy spawn) 2025-05-15 21:58:42.188871 :97920DE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:58:42.189038 :97920DE6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:58:42.189945 :97920DEB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:00.628681 :979213DE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:00.623 2025-05-15 21:59:00.628683 :979213DF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13056 pso_num=22 pso_serial#=201 2025-05-15 21:59:00.628816 :979213E0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13056 osp_idx=0 osp_ver=198081 osp_pg=0 (spawn #185818) 2025-05-15 21:59:00.628816 :979213E1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-15 21:59:00.628817 :979213E2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=196 time=322150500 2025-05-15 21:59:00.628817 :979213E3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 21:59:00.628818 :979213E4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 21:59:00.628818 :979213E5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 21:59:00.628818 :979213E6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 21:59:00.628818 :979213E7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 21:59:00.629294 :979213E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-15 21:59:00.629942 :979213FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:00.637454 :97921419:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-15 21:59:00.637817 :9792141A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:00.637854 :9792141D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-15 21:59:00.638678 :97921423:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:00.638798 :9792142C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:30.750953 :97921E26:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:44.373984 :97922252:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:44.365 2025-05-15 21:59:44.373986 :97922253:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11580 pso_num=22 pso_serial#=202 2025-05-15 21:59:44.374115 :97922254:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11580 (legacy spawn) 2025-05-15 21:59:44.398188 :97922255:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:44.398371 :9792225E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:44.399298 :9792225F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:44.817060 :9792228A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:44.803 2025-05-15 21:59:44.817062 :9792228B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12152 pso_num=22 pso_serial#=203 2025-05-15 21:59:44.817201 :9792228C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12152 (legacy spawn) 2025-05-15 21:59:44.823686 :9792228D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:44.823853 :97922292:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:44.824673 :97922297:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:44.833255 :9792229A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:44.818 2025-05-15 21:59:44.833257 :9792229B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3024 pso_num=22 pso_serial#=204 2025-05-15 21:59:44.833397 :9792229C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=3024 (legacy spawn) 2025-05-15 21:59:44.839749 :9792229D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:44.839912 :979222A6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:44.840703 :979222A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:45.061616 :979222C0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:45.053 2025-05-15 21:59:45.061617 :979222C1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12936 pso_num=22 pso_serial#=205 2025-05-15 21:59:45.061748 :979222C2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12936 (legacy spawn) 2025-05-15 21:59:45.068163 :979222C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:45.068341 :979222CB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:45.069145 :979222CD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:48.091634 :979223C8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:48.078 2025-05-15 21:59:48.091636 :979223C9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11708 pso_num=22 pso_serial#=206 2025-05-15 21:59:48.091773 :979223CA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11708 (legacy spawn) 2025-05-15 21:59:48.098284 :979223CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:48.098457 :979223D2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:48.099276 :979223D5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 21:59:48.107866 :979223D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 21:59:48.094 2025-05-15 21:59:48.107868 :979223D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12732 pso_num=22 pso_serial#=207 2025-05-15 21:59:48.108005 :979223DA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12732 (legacy spawn) 2025-05-15 21:59:48.114508 :979223DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-15 21:59:48.114674 :979223E4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-15 21:59:48.115492 :979223E5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-15 22:00:00.031637 :979227C3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-15 22:00:00.024 2025-05-15 22:00:00.031639 :979227C4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9660 pso_num=22 pso_serial#=208 2025-05-15 22:00:00.031749 :979227C5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9660 osp_idx=0 osp_ver=198088 osp_pg=0 (spawn #185820) 2025-05-15 22:00:00.031749 :979227C6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-15 22:00:00.031750 :979227C7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=322209907 2025-05-15 22:00:00.031750 :979227C8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-15 22:00:00.031751 :979227C9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-15 22:00:00.031751 :979227CA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-15 22:00:00.031751 :979227CB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-15 22:00:00.031752 :979227CC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-15 22:00:00.031824 :979227CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-15 22:00:00.031831 :979227CE: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: 9660, VKRM)