Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250731220009\orcl_j004_18448_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:35872M/63366M, Ph+PgF:41557M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 18448, image: ORACLE.EXE (J004) *** 2025-07-31 22:00:09.707 *** SESSION ID:(241.3743) 2025-07-31 22:00:09.707 *** 2025-07-31 22:00:09.707 Process diagnostic dump for ORACLE.EXE (J004), OS id=18448, pid: 30, proc_ser: 228, sid: 241, sess_ser: 3743 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18448 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) 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.183885 sec, exc=0.183885 sec, total=0.183885 sec wait times: max=0.500000 sec, heur=5.693341 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.000011 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.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.000009 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.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.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.000011 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.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.000008 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.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.500013 sec, exc=0.500013 sec, total=0.500013 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000009 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.000006 sec of elapsed time Sampled Session History of session 241 serial 3743 --------------------------------------------------- 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:04 - 22:00:09] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-31 22:00:09.707 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 18448, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-31 00:03:58.898724 :B93448BD:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:03:58.916458 :B93448C0:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:03:58.909 2025-07-31 00:03:58.916460 :B93448C1:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17944 pso_num=30 pso_serial#=214 2025-07-31 00:03:58.916599 :B93448C2:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17944 (legacy spawn) 2025-07-31 00:03:58.923012 :B93448C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:03:58.923195 :B93448CC:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:03:58.924010 :B93448CD:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:04:12.017489 :B9344CF2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:04:12.008 2025-07-31 00:04:12.017491 :B9344CF3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=19108 pso_num=30 pso_serial#=215 2025-07-31 00:04:12.017635 :B9344CF4:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=19108 (legacy spawn) 2025-07-31 00:04:12.024162 :B9344CF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:04:12.024324 :B9344CFE:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:04:12.025117 :B9344CFF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:04:56.501682 :B9345B32:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:04:56.489 2025-07-31 00:04:56.501684 :B9345B33:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=19316 pso_num=30 pso_serial#=216 2025-07-31 00:04:56.501820 :B9345B34:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=19316 (legacy spawn) 2025-07-31 00:04:56.508296 :B9345B35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:04:56.508485 :B9345B3A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:04:56.509282 :B9345B3F:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:08.307460 :B9345EC9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:08.293 2025-07-31 00:05:08.307462 :B9345ECA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20340 pso_num=30 pso_serial#=217 2025-07-31 00:05:08.307631 :B9345ECB:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20340 (legacy spawn) 2025-07-31 00:05:08.314059 :B9345ECC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:08.314262 :B9345ED1:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:08.315066 :B9345ED6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:08.323743 :B9345ED9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:08.308 2025-07-31 00:05:08.323746 :B9345EDA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20324 pso_num=30 pso_serial#=218 2025-07-31 00:05:08.323886 :B9345EDB:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20324 (legacy spawn) 2025-07-31 00:05:08.330355 :B9345EDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:08.330513 :B9345EE5:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:08.331345 :B9345EE6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:11.592010 :B9346432:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:11.578 2025-07-31 00:05:11.592012 :B9346433:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17112 pso_num=30 pso_serial#=219 2025-07-31 00:05:11.592150 :B9346434:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17112 (legacy spawn) 2025-07-31 00:05:11.598639 :B9346435:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:11.598828 :B934643A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:11.599648 :B934643F:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:11.608350 :B9346442:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:11.594 2025-07-31 00:05:11.608353 :B9346443:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15000 pso_num=30 pso_serial#=220 2025-07-31 00:05:11.608493 :B9346444:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=15000 (legacy spawn) 2025-07-31 00:05:11.615199 :B9346445:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:11.615351 :B934644E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:11.616304 :B934644F:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:20.221689 :B9346809:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:20.213 2025-07-31 00:05:20.221691 :B934680A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17488 pso_num=30 pso_serial#=221 2025-07-31 00:05:20.221869 :B934680B:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17488 (legacy spawn) 2025-07-31 00:05:20.228362 :B934680C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:20.228522 :B9346811:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:20.229385 :B9346816:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:54.362264 :B93474A2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:54.348 2025-07-31 00:05:54.362266 :B93474A3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16392 pso_num=30 pso_serial#=222 2025-07-31 00:05:54.362403 :B93474A4:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16392 (legacy spawn) 2025-07-31 00:05:54.368985 :B93474A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:54.369181 :B93474AA:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:54.369998 :B93474AF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:05:58.735604 :B9347615:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:05:58.727 2025-07-31 00:05:58.735606 :B9347616:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20240 pso_num=30 pso_serial#=223 2025-07-31 00:05:58.735736 :B9347617:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20240 (legacy spawn) 2025-07-31 00:05:58.742235 :B9347618:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:05:58.742423 :B934761D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:05:58.743249 :B9347622:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 00:06:15.299578 :B9347B2B:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 00:06:15.292 2025-07-31 00:06:15.299580 :B9347B2C:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17608 pso_num=30 pso_serial#=224 2025-07-31 00:06:15.299722 :B9347B2D:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17608 (legacy spawn) 2025-07-31 00:06:15.306199 :B9347B2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 00:06:15.306372 :B9347B33:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 00:06:15.307195 :B9347B38:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 01:00:15.989183 :B938A3AE:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 01:00:15.981 2025-07-31 01:00:15.989185 :B938A3AF:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12568 pso_num=30 pso_serial#=225 2025-07-31 01:00:15.989320 :B938A3B0:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=12568 (legacy spawn) 2025-07-31 01:00:15.995854 :B938A3B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:15.996187 :B938A3B8:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 01:00:15.996997 :B938A3BA:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 01:00:16.005663 :B938A3BD:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 01:00:15.996 2025-07-31 01:00:16.005665 :B938A3BE:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18760 pso_num=30 pso_serial#=226 2025-07-31 01:00:16.005795 :B938A3BF:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=18760 (legacy spawn) 2025-07-31 01:00:16.012261 :B938A3C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.012420 :B938A3C9:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 01:00:16.013214 :B938A3CA:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 01:00:16.471184 :B938A416:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 01:00:16.460 2025-07-31 01:00:16.471186 :B938A417:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=19204 pso_num=30 pso_serial#=227 2025-07-31 01:00:16.471325 :B938A418:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=19204 osp_idx=28 osp_ver=1756 osp_pg=0 (spawn #439580) 2025-07-31 01:00:16.471326 :B938A419:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 01:00:16.471326 :B938A41A:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=187 time=2604392361 2025-07-31 01:00:16.471326 :B938A41B:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 01:00:16.471327 :B938A41C:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 01:00:16.471327 :B938A41D:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 01:00:16.471327 :B938A41E:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 01:00:16.471328 :B938A41F:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 01:00:16.471827 :B938A420:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.471872 :B938A427:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-31 01:00:16.479526 :B938A434:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.480643 :B938A43C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.481207 :B938A444:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.481671 :B938A44C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.482201 :B938A454:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.484868 :B938A45C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.486006 :B938A464:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.535967 :B938A46C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.536887 :B938A474:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.537134 :B938A47C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.537372 :B938A484:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 01:00:16.539228 :B938A48C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-31 22:00:02.063032 :B99C6F59:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-31 22:00:02.061 2025-07-31 22:00:02.063033 :B99C6F5A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18448 pso_num=30 pso_serial#=228 2025-07-31 22:00:02.063156 :B99C6F5B:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18448 osp_idx=28 osp_ver=1757 osp_pg=0 (spawn #442485) 2025-07-31 22:00:02.063156 :B99C6F5C:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 22:00:02.063157 :B99C6F5D:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=188 time=2679977939 2025-07-31 22:00:02.063157 :B99C6F5E:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 22:00:02.063157 :B99C6F5F:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 22:00:02.063158 :B99C6F60:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 22:00:02.063158 :B99C6F61:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 22:00:02.063159 :B99C6F62:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 22:00:02.063911 :B99C6F63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.068963 :B99C70AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.069022 :B99C70AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-07-31 22:00:02.074062 :B99C70D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.306177 :B99C724A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.508376 :B99C726A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.641616 :B99C728D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:02.765071 :B99C729D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.770652 :B99C758C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.771700 :B99C7594:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.773411 :B99C759C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.777540 :B99C75A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.781019 :B99C75B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.782568 :B99C75C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.784169 :B99C75C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.785728 :B99C75D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.807931 :B99C75E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.808562 :B99C75ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.809073 :B99C75F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.809568 :B99C7605:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.813371 :B99C762C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.814254 :B99C762D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.815107 :B99C7636:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.815560 :B99C763F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.815566 :B99C7640:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.816194 :B99C7641:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.816705 :B99C7642:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.816997 :B99C7643:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.861295 :B99C7644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.862317 :B99C764C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.896752 :B99C7675:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.896797 :B99C7678:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.897703 :B99C767E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.898564 :B99C7686:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.898996 :B99C7687:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.918932 :B99C768F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.918979 :B99C7692:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.919837 :B99C7698:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.920793 :B99C76A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.921283 :B99C76A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.938276 :B99C76A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.938324 :B99C76AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.939181 :B99C76B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.940176 :B99C76BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.940702 :B99C76BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.961774 :B99C76CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.961824 :B99C76CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.962740 :B99C76D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.963618 :B99C76DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.964291 :B99C76DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.984440 :B99C76E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.984486 :B99C76EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:03.985388 :B99C76F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:03.997465 :B99C76F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-31 22:00:03.998097 :B99C76F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.016039 :B99C7712:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.016081 :B99C7715:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-31 22:00:04.016777 :B99C7720:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=24 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-31 22:00:04.017086 :B99C7723:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.017564 :B99C772F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.017906 :B99C7741:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.018087 :B99C7749:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.018217 :B99C7756:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.018367 :B99C775E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-07-31 22:00:04.022299 :B99C775F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.022380 :B99C7762:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-31 22:00:04.022469 :B99C776F:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 18448, J004)