Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250628140711\orcl_j002_15800_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:36368M/63366M, Ph+PgF:42470M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 15800, image: ORACLE.EXE (J002) *** 2025-06-28 14:07:11.763 *** SESSION ID:(219.21511) 2025-06-28 14:07:11.763 *** 2025-06-28 14:07:11.763 Process diagnostic dump for ORACLE.EXE (J002), OS id=15800, pid: 27, proc_ser: 86, sid: 219, sess_ser: 21511 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15800 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.000496 sec, exc=0.000496 sec, total=0.000496 sec wait times: max=0.500000 sec, heur=6.392337 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=12 seq_num=13 snap_id=1 wait times: snap=0.501116 sec, exc=0.501116 sec, total=0.501116 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 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=9 seq_num=10 snap_id=1 wait times: snap=0.507489 sec, exc=0.507489 sec, total=0.507489 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=8 seq_num=9 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.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499033 sec, exc=0.499033 sec, total=0.499033 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.365447 sec, exc=0.365447 sec, total=0.365447 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=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.000011 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time Sampled Session History of session 219 serial 21511 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 14:07:05 - 14:07:11] idle wait at each sample [session created at: 14:07:05] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-28 14:07:11.763 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 15800, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-28 14:01:27.397389 :AACB6795:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:01:27.397564 :AACB679D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:01:27.398370 :AACB679F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:01:27.407026 :AACB67A2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:01:27.392 2025-06-28 14:01:27.407028 :AACB67A3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13344 pso_num=27 pso_serial#=59 2025-06-28 14:01:27.407170 :AACB67A4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13344 (legacy spawn) 2025-06-28 14:01:27.413686 :AACB67A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:01:27.413853 :AACB67AE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:01:27.414659 :AACB67AF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:01:30.522500 :AACB68A0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:01:30.509 2025-06-28 14:01:30.522502 :AACB68A1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13808 pso_num=27 pso_serial#=60 2025-06-28 14:01:30.522638 :AACB68A2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13808 (legacy spawn) 2025-06-28 14:01:30.529130 :AACB68A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:01:30.529307 :AACB68A9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:01:30.530132 :AACB68AD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:01:30.549467 :AACB68B0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:01:30.540 2025-06-28 14:01:30.549469 :AACB68B1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17348 pso_num=27 pso_serial#=61 2025-06-28 14:01:30.549602 :AACB68B2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17348 (legacy spawn) 2025-06-28 14:01:30.556043 :AACB68B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:01:30.556205 :AACB68BC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:01:30.557020 :AACB68BD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:02:24.551006 :AACB7BAA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:02:24.537 2025-06-28 14:02:24.551008 :AACB7BAB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14232 pso_num=27 pso_serial#=62 2025-06-28 14:02:24.551140 :AACB7BAC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14232 (legacy spawn) 2025-06-28 14:02:24.557595 :AACB7BAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:02:24.557770 :AACB7BB2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:02:24.558605 :AACB7BB7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:02:35.546026 :AACB7F0E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:02:35.537 2025-06-28 14:02:35.546028 :AACB7F0F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17476 pso_num=27 pso_serial#=63 2025-06-28 14:02:35.546169 :AACB7F10:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17476 (legacy spawn) 2025-06-28 14:02:35.552641 :AACB7F11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:02:35.552806 :AACB7F1A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:02:35.553628 :AACB7F1B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:09.543380 :AACB8A93:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:09.534 2025-06-28 14:03:09.543382 :AACB8A94:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16548 pso_num=27 pso_serial#=64 2025-06-28 14:03:09.543517 :AACB8A95:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16548 (legacy spawn) 2025-06-28 14:03:09.549999 :AACB8A96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:09.550160 :AACB8A9B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:09.550952 :AACB8AA0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:16.414895 :AACB8CB3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:16.401 2025-06-28 14:03:16.414897 :AACB8CB4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4508 pso_num=27 pso_serial#=65 2025-06-28 14:03:16.415036 :AACB8CB5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4508 (legacy spawn) 2025-06-28 14:03:16.421417 :AACB8CB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:16.421589 :AACB8CBF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:16.422386 :AACB8CC0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:16.431093 :AACB8CC3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:16.417 2025-06-28 14:03:16.431095 :AACB8CC4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15744 pso_num=27 pso_serial#=66 2025-06-28 14:03:16.431233 :AACB8CC5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15744 (legacy spawn) 2025-06-28 14:03:16.452054 :AACB8CC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:16.452217 :AACB8CCF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:16.453001 :AACB8CD0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:17.289425 :AACB8D02:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:17.276 2025-06-28 14:03:17.289427 :AACB8D03:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16524 pso_num=27 pso_serial#=67 2025-06-28 14:03:17.289566 :AACB8D04:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16524 (legacy spawn) 2025-06-28 14:03:17.296020 :AACB8D05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:17.296192 :AACB8D0A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:17.296979 :AACB8D0F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:17.305565 :AACB8D12:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:17.292 2025-06-28 14:03:17.305566 :AACB8D13:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8216 pso_num=27 pso_serial#=68 2025-06-28 14:03:17.305701 :AACB8D14:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8216 (legacy spawn) 2025-06-28 14:03:17.312109 :AACB8D15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:17.312275 :AACB8D1E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:17.313094 :AACB8D1F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:19.505003 :AACB8DEA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:19.497 2025-06-28 14:03:19.505005 :AACB8DEB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7960 pso_num=27 pso_serial#=69 2025-06-28 14:03:19.505198 :AACB8DEC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7960 (legacy spawn) 2025-06-28 14:03:19.511699 :AACB8DED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:19.511865 :AACB8DF2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:19.512684 :AACB8DF7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:19.521411 :AACB8DFA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:19.512 2025-06-28 14:03:19.521413 :AACB8DFB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12644 pso_num=27 pso_serial#=70 2025-06-28 14:03:19.521547 :AACB8DFC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12644 (legacy spawn) 2025-06-28 14:03:19.527989 :AACB8DFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:19.528169 :AACB8E06:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:19.528996 :AACB8E07:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:03:23.057632 :AACB8F21:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:03:23.049 2025-06-28 14:03:23.057635 :AACB8F22:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11796 pso_num=27 pso_serial#=71 2025-06-28 14:03:23.057776 :AACB8F23:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11796 (legacy spawn) 2025-06-28 14:03:23.064384 :AACB8F24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:03:23.064550 :AACB8F2D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:03:23.065386 :AACB8F2E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:04:17.740905 :AACBA0EA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:04:17.731 2025-06-28 14:04:17.740907 :AACBA0EB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4196 pso_num=27 pso_serial#=72 2025-06-28 14:04:17.741046 :AACBA0EC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4196 (legacy spawn) 2025-06-28 14:04:17.747457 :AACBA0ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:04:17.747629 :AACBA0F2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:04:17.748416 :AACBA0F7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:04:28.921338 :AACBA496:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:04:28.918 2025-06-28 14:04:28.921340 :AACBA497:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17060 pso_num=27 pso_serial#=73 2025-06-28 14:04:28.921478 :AACBA498:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17060 (legacy spawn) 2025-06-28 14:04:28.928019 :AACBA499:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:04:28.928190 :AACBA49E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:04:28.928946 :AACBA4A3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:04:28.931271 :AACBA4A6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:04:28.918 2025-06-28 14:04:28.931273 :AACBA4A7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13448 pso_num=27 pso_serial#=74 2025-06-28 14:04:28.931403 :AACBA4A8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13448 (legacy spawn) 2025-06-28 14:04:28.937861 :AACBA4A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:04:28.938046 :AACBA4B2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:04:28.938828 :AACBA4B3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:04:28.940964 :AACBA4B6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:04:28.934 2025-06-28 14:04:28.940966 :AACBA4B7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9632 pso_num=27 pso_serial#=75 2025-06-28 14:04:28.941097 :AACBA4B8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9632 (legacy spawn) 2025-06-28 14:04:28.947557 :AACBA4B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:04:28.947719 :AACBA4C2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:04:28.948521 :AACBA4C3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:04:32.114258 :AACBA5AC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:04:32.101 2025-06-28 14:04:32.114259 :AACBA5AD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4288 pso_num=27 pso_serial#=76 2025-06-28 14:04:32.114395 :AACBA5AE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4288 (legacy spawn) 2025-06-28 14:04:32.120785 :AACBA5AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:04:32.120960 :AACBA5B4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:04:32.121784 :AACBA5B9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:04:32.130376 :AACBA5BC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:04:32.116 2025-06-28 14:04:32.130378 :AACBA5BD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6736 pso_num=27 pso_serial#=77 2025-06-28 14:04:32.130516 :AACBA5BE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6736 (legacy spawn) 2025-06-28 14:04:32.136903 :AACBA5BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:04:32.137075 :AACBA5C8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:04:32.137870 :AACBA5C9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:05:25.929494 :AACBBE5E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:05:25.916 2025-06-28 14:05:25.929496 :AACBBE5F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13528 pso_num=27 pso_serial#=78 2025-06-28 14:05:25.929629 :AACBBE60:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13528 (legacy spawn) 2025-06-28 14:05:25.936053 :AACBBE61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:05:25.936228 :AACBBE66:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:05:25.937002 :AACBBE6B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:05:31.260330 :AACBC037:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:05:31.252 2025-06-28 14:05:31.260332 :AACBC038:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14080 pso_num=27 pso_serial#=79 2025-06-28 14:05:31.260471 :AACBC039:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14080 (legacy spawn) 2025-06-28 14:05:31.267005 :AACBC03A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:05:31.267180 :AACBC03F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:05:31.268001 :AACBC044:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:06:17.708214 :AACBCF56:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:06:17.700 2025-06-28 14:06:17.708216 :AACBCF57:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13824 pso_num=27 pso_serial#=80 2025-06-28 14:06:17.708354 :AACBCF58:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13824 (legacy spawn) 2025-06-28 14:06:17.714820 :AACBCF59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:06:17.715001 :AACBCF5E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:06:17.715973 :AACBCF6B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:06:17.724631 :AACBCF72:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:06:17.716 2025-06-28 14:06:17.724634 :AACBCF73:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1396 pso_num=27 pso_serial#=81 2025-06-28 14:06:17.724775 :AACBCF74:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1396 (legacy spawn) 2025-06-28 14:06:17.731290 :AACBCF75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:06:17.731445 :AACBCF7B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:06:17.732224 :AACBCF7F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:06:20.802170 :AACBD077:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:06:20.788 2025-06-28 14:06:20.802172 :AACBD078:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9412 pso_num=27 pso_serial#=82 2025-06-28 14:06:20.802317 :AACBD079:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9412 (legacy spawn) 2025-06-28 14:06:20.808743 :AACBD07A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:06:20.808942 :AACBD07F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:06:20.809739 :AACBD084:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:06:20.818410 :AACBD087:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:06:20.804 2025-06-28 14:06:20.818412 :AACBD088:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17008 pso_num=27 pso_serial#=83 2025-06-28 14:06:20.818553 :AACBD089:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17008 (legacy spawn) 2025-06-28 14:06:20.825129 :AACBD08A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:06:20.825308 :AACBD093:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:06:20.826115 :AACBD094:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:06:33.441507 :AACBD466:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:06:33.427 2025-06-28 14:06:33.441509 :AACBD467:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14488 pso_num=27 pso_serial#=84 2025-06-28 14:06:33.441660 :AACBD468:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14488 (legacy spawn) 2025-06-28 14:06:33.448506 :AACBD469:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:06:33.448693 :AACBD471:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:06:33.449645 :AACBD473:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:06:35.102944 :AACBD524:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:06:35.094 2025-06-28 14:06:35.102947 :AACBD525:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13868 pso_num=27 pso_serial#=85 2025-06-28 14:06:35.103084 :AACBD526:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13868 (legacy spawn) 2025-06-28 14:06:35.109563 :AACBD527:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 14:06:35.110114 :AACBD53F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 14:06:35.111157 :AACBD554:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 14:07:05.370870 :AACBE3D0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 14:07:05.365 2025-06-28 14:07:05.370872 :AACBE3D1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15800 pso_num=27 pso_serial#=86 2025-06-28 14:07:05.371020 :AACBE3D2:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15800 osp_idx=25 osp_ver=108979 osp_pg=0 (spawn #331415) 2025-06-28 14:07:05.371021 :AACBE3D3:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 14:07:05.371021 :AACBE3D4:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=143 time=4095368563 2025-06-28 14:07:05.371021 :AACBE3D5:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 14:07:05.371022 :AACBE3D6:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 14:07:05.371022 :AACBE3D7:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 14:07:05.371022 :AACBE3D8:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 14:07:05.371023 :AACBE3D9:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 14:07:05.371530 :AACBE3E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 14:07:08.755104 :AACBE74A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 15800, J002)