Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250907220739\orcl_j000_24068_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:34959M/63366M, Ph+PgF:39782M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 24068, image: ORACLE.EXE (J000) *** 2025-09-07 22:07:39.100 *** SESSION ID:(201.47303) 2025-09-07 22:07:39.100 *** 2025-09-07 22:07:39.100 Process diagnostic dump for ORACLE.EXE (J000), OS id=24068, pid: 25, proc_ser: 12, sid: 201, sess_ser: 47303 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24068 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.228301 sec, exc=0.228301 sec, total=0.228301 sec wait times: max=0.500000 sec, heur=5.727642 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500103 sec, exc=0.500103 sec, total=0.500103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.502082 sec, exc=0.502082 sec, total=0.502082 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 201 serial 47303 --------------------------------------------------- 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:07:33 - 22:07:39] idle wait at each sample [session created at: 22:07:33] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-07 22:07:39.100 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 24068, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-07 22:06:24.426983 :CA606140:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24232 (legacy spawn) 2025-09-07 22:06:24.433615 :CA606141:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:24.433780 :CA606146:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:06:24.434594 :CA60614B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:06:30.795089 :CA60633A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:06:30.790 2025-09-07 22:06:30.795091 :CA60633B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23152 pso_num=25 pso_serial#=1 2025-09-07 22:06:30.795243 :CA60633C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23152 (legacy spawn) 2025-09-07 22:06:30.802279 :CA60633D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:30.802475 :CA606345:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:06:30.803378 :CA606347:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:06:30.813351 :CA60634A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:06:30.805 2025-09-07 22:06:30.813354 :CA60634B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24104 pso_num=25 pso_serial#=2 2025-09-07 22:06:30.813507 :CA60634C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24104 (legacy spawn) 2025-09-07 22:06:30.820320 :CA60634D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:30.820497 :CA606356:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:06:30.821320 :CA606357:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:06:31.467769 :CA6063AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:06:31.461 2025-09-07 22:06:31.467770 :CA6063AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22412 pso_num=25 pso_serial#=3 2025-09-07 22:06:31.467920 :CA6063AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22412 osp_idx=23 osp_ver=287911 osp_pg=0 (spawn #569183) 2025-09-07 22:06:31.467921 :CA6063AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 22:06:31.467922 :CA6063AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=1668442768 2025-09-07 22:06:31.467922 :CA6063B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 22:06:31.467922 :CA6063B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 22:06:31.467922 :CA6063B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 22:06:31.467923 :CA6063B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 22:06:31.467923 :CA6063B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 22:06:31.468475 :CA6063B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.469315 :CA6063CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.485061 :CA6063E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:06:31.485146 :CA6063E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:06:31.486101 :CA6063E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:06:31.486472 :CA6063E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.486516 :CA6063EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:06:31.495850 :CA6063F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.495957 :CA6063F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-09-07 22:06:31.496004 :CA6063F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-09-07 22:06:31.496427 :CA6063FC:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-07 22:06:31.496845 :CA6063FD:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-07 22:06:31.497056 :CA6063FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:06:31.497360 :CA6063FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.497391 :CA606402:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:06:31.499107 :CA606408:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:06:31.499343 :CA606409:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.499373 :CA60640C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:06:31.500409 :CA606412:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:31.500544 :CA60641A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:01.586914 :CA606DB8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:07.084406 :CA606F69:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:07.069 2025-09-07 22:07:07.084408 :CA606F6A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24404 pso_num=25 pso_serial#=4 2025-09-07 22:07:07.084544 :CA606F6B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=24404 (legacy spawn) 2025-09-07 22:07:07.091075 :CA606F6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:07.091243 :CA606F75:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:07.092023 :CA606F76:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:07.100816 :CA606F79:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:07.101 2025-09-07 22:07:07.100818 :CA606F7A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20544 pso_num=25 pso_serial#=5 2025-09-07 22:07:07.100960 :CA606F7B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20544 (legacy spawn) 2025-09-07 22:07:07.107521 :CA606F7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:07.107684 :CA606F85:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:07.108477 :CA606F86:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:10.213453 :CA60706A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:10.201 2025-09-07 22:07:10.213454 :CA60706B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21492 pso_num=25 pso_serial#=6 2025-09-07 22:07:10.213600 :CA60706C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21492 (legacy spawn) 2025-09-07 22:07:10.220339 :CA60706D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:10.220504 :CA607076:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:10.221292 :CA607077:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:10.223493 :CA60707A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:10.216 2025-09-07 22:07:10.223495 :CA60707B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21708 pso_num=25 pso_serial#=7 2025-09-07 22:07:10.223626 :CA60707C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21708 (legacy spawn) 2025-09-07 22:07:10.230214 :CA60707D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:10.230385 :CA607084:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:10.231184 :CA607087:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:10.233606 :CA60708A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:10.232 2025-09-07 22:07:10.233608 :CA60708B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23252 pso_num=25 pso_serial#=8 2025-09-07 22:07:10.233740 :CA60708C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23252 (legacy spawn) 2025-09-07 22:07:10.240252 :CA60708D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:10.240421 :CA607096:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:10.241219 :CA607097:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:10.249763 :CA60709A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:10.248 2025-09-07 22:07:10.249765 :CA60709B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8240 pso_num=25 pso_serial#=9 2025-09-07 22:07:10.249901 :CA60709C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8240 (legacy spawn) 2025-09-07 22:07:10.256527 :CA60709D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:10.256694 :CA6070A6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:10.257516 :CA6070A7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:21.002900 :CA6073DA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:20.996 2025-09-07 22:07:21.002902 :CA6073DB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23288 pso_num=25 pso_serial#=10 2025-09-07 22:07:21.003045 :CA6073DC:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23288 (legacy spawn) 2025-09-07 22:07:21.009587 :CA6073DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:21.009898 :CA6073E2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:21.010716 :CA6073E7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:31.478363 :CA607770:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:31.473 2025-09-07 22:07:31.478365 :CA607771:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24564 pso_num=25 pso_serial#=11 2025-09-07 22:07:31.478504 :CA607772:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24564 osp_idx=23 osp_ver=287919 osp_pg=0 (spawn #569185) 2025-09-07 22:07:31.478505 :CA607773:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 22:07:31.478505 :CA607774:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=1668502783 2025-09-07 22:07:31.478507 :CA607775:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 22:07:31.478508 :CA607776:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 22:07:31.478508 :CA607777:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 22:07:31.478508 :CA607778:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 22:07:31.478508 :CA607779:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 22:07:31.479103 :CA60777A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.479150 :CA607780:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:31.500779 :CA607815:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-07 22:07:31.585920 :CA6078E0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-07 22:07:31.582 2025-09-07 22:07:31.585921 :CA6078E1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24068 pso_num=25 pso_serial#=12 2025-09-07 22:07:31.586039 :CA6078E2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24068 osp_idx=23 osp_ver=287920 osp_pg=0 (spawn #569188) 2025-09-07 22:07:31.586040 :CA6078E3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 22:07:31.586041 :CA6078E4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=1668502877 2025-09-07 22:07:31.586041 :CA6078E5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 22:07:31.586042 :CA6078E6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 22:07:31.586042 :CA6078E7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 22:07:31.586042 :CA6078E8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 22:07:31.586042 :CA6078E9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 22:07:31.587506 :CA6078EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.588241 :CA6078FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.596429 :CA607926:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:31.596874 :CA607927:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.596907 :CA60792A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:31.597883 :CA607930:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.598020 :CA607939:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-07 22:07:31.709146 :CA6079FA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 22:07:31.714313 :CA6079FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.714351 :CA607A01:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-07 22:07:31.718376 :CA607A07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.826160 :CA607A10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.954214 :CA607A18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:32.081234 :CA607A2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.197675 :CA607AEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.198640 :CA607AF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.200387 :CA607AFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.202840 :CA607B03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.204401 :CA607B0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.205852 :CA607B13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.222052 :CA607B1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.222704 :CA607B23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.223354 :CA607B2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.223875 :CA607B33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.226449 :CA607B3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.227441 :CA607B43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.228196 :CA607B44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.228952 :CA607B45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.229378 :CA607B46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.229384 :CA607B47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.229970 :CA607B48:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.230470 :CA607B49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.230779 :CA607B4A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.231901 :CA607B4B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.267666 :CA607B4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.267712 :CA607B4F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.268573 :CA607B55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.269446 :CA607B5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.269931 :CA607B5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.291283 :CA607B66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.291343 :CA607B69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.292222 :CA607B6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.304318 :CA607B81:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.304877 :CA607B82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.326061 :CA607BB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.326103 :CA607BB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.326865 :CA607BCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.327654 :CA607BD2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.328060 :CA607BD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.347709 :CA607BDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.347760 :CA607BDE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.348645 :CA607BE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.349660 :CA607BEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-09-07 22:07:33.350160 :CA607BED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.372834 :CA607BF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.372888 :CA607BF8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-09-07 22:07:33.373824 :CA607BFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.374333 :CA607C06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.374704 :CA607C0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.374889 :CA607C16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.375029 :CA607C1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.375190 :CA607C26:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-07 22:07:33.379468 :CA607C27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.379552 :CA607C2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-07 22:07:33.379633 :CA607C37:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 24068, J000)