Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629060006\orcl_j005_12024_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:36552M/63366M, Ph+PgF:42649M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 12024, image: ORACLE.EXE (J005) *** 2025-06-29 06:00:06.605 *** SESSION ID:(249.1221) 2025-06-29 06:00:06.605 *** 2025-06-29 06:00:06.605 Process diagnostic dump for ORACLE.EXE (J005), OS id=12024, pid: 31, proc_ser: 84, sid: 249, sess_ser: 1221 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12024 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.052169 sec, exc=0.052169 sec, total=0.052169 sec wait times: max=0.500000 sec, heur=4.565009 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500113 sec, exc=0.500113 sec, total=0.500113 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.511389 sec, exc=0.511389 sec, total=0.511389 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.510100 sec, exc=0.510100 sec, total=0.510100 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=0 seq_num=1 snap_id=1 wait times: snap=0.491174 sec, exc=0.491174 sec, total=0.491174 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 249 serial 1221 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 06:00:02 - 06:00:06] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 06:00:06.605 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 12024, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-25 23:59:18.039408 :A9A52003:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:18.024 2025-06-25 23:59:18.039410 :A9A52004:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13328 pso_num=31 pso_serial#=59 2025-06-25 23:59:18.039547 :A9A52005:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13328 (legacy spawn) 2025-06-25 23:59:18.054047 :A9A52006:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-25 23:59:21.343734 :A9A520F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-25 23:59:21.343910 :A9A520FB:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-25 23:59:21.345119 :A9A52100:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:21.436270 :A9A52103:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:21.427 2025-06-25 23:59:21.436272 :A9A52104:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4280 pso_num=31 pso_serial#=60 2025-06-25 23:59:21.436419 :A9A52105:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4280 (legacy spawn) 2025-06-25 23:59:21.443421 :A9A52106:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-25 23:59:21.443649 :A9A5210F:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-25 23:59:21.444583 :A9A52110:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:21.594633 :A9A52113:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:21.583 2025-06-25 23:59:21.594634 :A9A52114:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18028 pso_num=31 pso_serial#=61 2025-06-25 23:59:21.594759 :A9A52115:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18028 (legacy spawn) 2025-06-25 23:59:21.608387 :A9A52116:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-25 23:59:54.780073 :A9A52DCE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:54.808467 :A9A52DD1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:54.809 2025-06-25 23:59:54.808469 :A9A52DD2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10992 pso_num=31 pso_serial#=62 2025-06-25 23:59:54.808616 :A9A52DD3:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10992 (legacy spawn) 2025-06-25 23:59:54.823159 :A9A52DD4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-25 23:59:58.116476 :A9A52F0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-25 23:59:58.116650 :A9A52F14:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-25 23:59:58.118188 :A9A52F15:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-25 23:59:58.365293 :A9A52F23:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-25 23:59:58.354 2025-06-25 23:59:58.365295 :A9A52F24:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14644 pso_num=31 pso_serial#=63 2025-06-25 23:59:58.365459 :A9A52F25:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14644 (legacy spawn) 2025-06-25 23:59:58.379884 :A9A52F26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 00:06:18.336199 :A9A5BBF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 00:06:18.336383 :A9A5BBFE:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-26 00:06:18.337533 :A9A5BC03:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 22:00:02.060772 :AA0DB393:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 22:00:02.051 2025-06-26 22:00:02.060774 :AA0DB394:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16500 pso_num=31 pso_serial#=64 2025-06-26 22:00:02.060964 :AA0DB39D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=16500 osp_idx=29 osp_ver=573 osp_pg=0 (spawn #325828) 2025-06-26 22:00:02.060965 :AA0DB39E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-26 22:00:02.060966 :AA0DB39F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=176 time=3950945250 2025-06-26 22:00:02.060966 :AA0DB3A0:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 22:00:02.060967 :AA0DB3A1:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 22:00:02.060967 :AA0DB3A2:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 22:00:02.060967 :AA0DB3A3:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 22:00:02.060968 :AA0DB3A4:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 22:00:02.061552 :AA0DB3A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-26 22:00:32.087505 :AA0DC565:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:07.298682 :AA1724F1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:07.285 2025-06-26 23:59:07.298684 :AA1724F2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14356 pso_num=31 pso_serial#=65 2025-06-26 23:59:07.298828 :AA1724F3:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14356 (legacy spawn) 2025-06-26 23:59:07.305705 :AA1724F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:07.305880 :AA1724F9:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-26 23:59:07.306872 :AA1724FE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:07.315441 :AA172501:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:07.301 2025-06-26 23:59:07.315443 :AA172502:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13920 pso_num=31 pso_serial#=66 2025-06-26 23:59:07.315594 :AA172503:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13920 (legacy spawn) 2025-06-26 23:59:07.333863 :AA172504:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:07.334028 :AA17250D:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-26 23:59:07.334883 :AA17250E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:09.610340 :AA1725C3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:09.608 2025-06-26 23:59:09.610342 :AA1725C4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=5020 pso_num=31 pso_serial#=67 2025-06-26 23:59:09.610491 :AA1725C5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=5020 (legacy spawn) 2025-06-26 23:59:09.625410 :AA1725D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:12.969910 :AA1726CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:12.970097 :AA1726D2:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-26 23:59:12.971264 :AA1726D7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:13.219280 :AA172723:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:13.205 2025-06-26 23:59:13.219282 :AA172724:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17584 pso_num=31 pso_serial#=68 2025-06-26 23:59:13.219431 :AA172725:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17584 (legacy spawn) 2025-06-26 23:59:13.233351 :AA172726:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:46.452672 :AA173349:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:46.471284 :AA17334C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:46.465 2025-06-26 23:59:46.471287 :AA17334D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17736 pso_num=31 pso_serial#=69 2025-06-26 23:59:46.471421 :AA17334E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17736 (legacy spawn) 2025-06-26 23:59:46.488406 :AA17334F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:46.493979 :AA173354:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-26 23:59:49.834706 :AA1734B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-26 23:59:49.834889 :AA1734BA:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-26 23:59:49.836019 :AA1734BF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-26 23:59:50.086680 :AA1734EA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-26 23:59:50.076 2025-06-26 23:59:50.086683 :AA1734EB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18264 pso_num=31 pso_serial#=70 2025-06-26 23:59:50.086829 :AA1734EC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18264 (legacy spawn) 2025-06-26 23:59:50.101397 :AA1734ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 00:00:23.204373 :AA174136:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 22:00:02.061841 :AA7F4389:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 22:00:02.053 2025-06-27 22:00:02.061842 :AA7F438A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6364 pso_num=31 pso_serial#=71 2025-06-27 22:00:02.061970 :AA7F438B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=6364 osp_idx=29 osp_ver=580 osp_pg=0 (spawn #329162) 2025-06-27 22:00:02.061971 :AA7F438C:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 22:00:02.061972 :AA7F438D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=177 time=4037345250 2025-06-27 22:00:02.061972 :AA7F438E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 22:00:02.061973 :AA7F438F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 22:00:02.061973 :AA7F4390:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 22:00:02.061973 :AA7F4391:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 22:00:02.061974 :AA7F4392:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 22:00:02.062734 :AA7F4393:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-27 22:00:32.161825 :AA7F55C1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 23:59:08.431778 :AA88979F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 23:59:08.422 2025-06-27 23:59:08.431780 :AA8897A0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14024 pso_num=31 pso_serial#=72 2025-06-27 23:59:08.431947 :AA8897A1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14024 (legacy spawn) 2025-06-27 23:59:08.439385 :AA8897A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 23:59:08.439567 :AA8897A7:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-27 23:59:08.440462 :AA8897AC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 23:59:09.989617 :AA88989C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 23:59:09.981 2025-06-27 23:59:09.989619 :AA88989D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16876 pso_num=31 pso_serial#=73 2025-06-27 23:59:09.989730 :AA88989E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16876 (legacy spawn) 2025-06-27 23:59:10.003268 :AA88989F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 23:59:13.329877 :AA88999A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 23:59:13.330052 :AA88999F:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-27 23:59:13.331336 :AA8899A4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 23:59:13.580125 :AA8899A7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 23:59:13.572 2025-06-27 23:59:13.580127 :AA8899A8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=3236 pso_num=31 pso_serial#=74 2025-06-27 23:59:13.580262 :AA8899A9:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=3236 (legacy spawn) 2025-06-27 23:59:13.594354 :AA8899AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 23:59:46.769304 :AA88A561:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 23:59:46.828362 :AA88A564:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 23:59:46.813 2025-06-27 23:59:46.828364 :AA88A565:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18052 pso_num=31 pso_serial#=75 2025-06-27 23:59:46.828480 :AA88A566:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18052 (legacy spawn) 2025-06-27 23:59:46.841874 :AA88A567:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 23:59:50.159032 :AA88A66B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-27 23:59:50.159205 :AA88A670:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-27 23:59:50.160467 :AA88A675:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-27 23:59:50.417869 :AA88A678:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-27 23:59:50.417 2025-06-27 23:59:50.417871 :AA88A679:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16556 pso_num=31 pso_serial#=76 2025-06-27 23:59:50.418008 :AA88A67A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16556 (legacy spawn) 2025-06-27 23:59:50.432689 :AA88A67B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 00:00:23.581298 :AA88B37B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 06:00:02.240668 :AAA4CEFE:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 06:00:02.238 2025-06-28 06:00:02.240669 :AAA4CEFF:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8296 pso_num=31 pso_serial#=77 2025-06-28 06:00:02.240798 :AAA4CF00:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=8296 osp_idx=29 osp_ver=586 osp_pg=0 (spawn #330280) 2025-06-28 06:00:02.240799 :AAA4CF01:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 06:00:02.240799 :AAA4CF02:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=178 time=4066145422 2025-06-28 06:00:02.240800 :AAA4CF03:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 06:00:02.240800 :AAA4CF04:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 06:00:02.240800 :AAA4CF05:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 06:00:02.240801 :AAA4CF06:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 06:00:02.240801 :AAA4CF07:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 06:00:02.241347 :AAA4CF1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 06:00:32.323571 :AAA4E2CD:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 23:59:07.599869 :AAFAC180:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 23:59:07.585 2025-06-28 23:59:07.599871 :AAFAC181:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9416 pso_num=31 pso_serial#=78 2025-06-28 23:59:07.600006 :AAFAC182:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9416 (legacy spawn) 2025-06-28 23:59:07.606730 :AAFAC183:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:07.606924 :AAFAC188:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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 23:59:07.607704 :AAFAC18D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 23:59:07.616840 :AAFAC190:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 23:59:07.616 2025-06-28 23:59:07.616842 :AAFAC191:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17392 pso_num=31 pso_serial#=79 2025-06-28 23:59:07.616998 :AAFAC192:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17392 (legacy spawn) 2025-06-28 23:59:07.623862 :AAFAC193:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:07.624034 :AAFAC19C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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 23:59:07.624856 :AAFAC19D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 23:59:10.084465 :AAFAC277:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 23:59:10.073 2025-06-28 23:59:10.084467 :AAFAC278:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12636 pso_num=31 pso_serial#=80 2025-06-28 23:59:10.084607 :AAFAC279:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12636 (legacy spawn) 2025-06-28 23:59:10.099302 :AAFAC27A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:13.289580 :AAFAC396:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:13.289744 :AAFAC39F:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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 23:59:13.291099 :AAFAC3A0:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 23:59:13.540771 :AAFAC3CB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 23:59:13.538 2025-06-28 23:59:13.540773 :AAFAC3CC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16320 pso_num=31 pso_serial#=81 2025-06-28 23:59:13.540921 :AAFAC3CD:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16320 (legacy spawn) 2025-06-28 23:59:13.555738 :AAFAC3CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:46.823554 :AAFACFD8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 23:59:51.586013 :AAFAD17D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 23:59:51.586 2025-06-28 23:59:51.586016 :AAFAD17E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16604 pso_num=31 pso_serial#=82 2025-06-28 23:59:51.586183 :AAFAD17F:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16604 (legacy spawn) 2025-06-28 23:59:51.601227 :AAFAD180:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:54.866285 :AAFAD2C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-28 23:59:54.866455 :AAFAD2CB:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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 23:59:54.867761 :AAFAD2CC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-28 23:59:55.119322 :AAFAD2E6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-28 23:59:55.113 2025-06-28 23:59:55.119324 :AAFAD2E7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17844 pso_num=31 pso_serial#=83 2025-06-28 23:59:55.119457 :AAFAD2E8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17844 (legacy spawn) 2025-06-28 23:59:55.134182 :AAFAD2E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 00:00:28.311646 :AAFAE185:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-06-29 06:00:02.044734 :AB170613:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-29 06:00:02.037 2025-06-29 06:00:02.044735 :AB170614:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12024 pso_num=31 pso_serial#=84 2025-06-29 06:00:02.044891 :AB170616:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12024 osp_idx=29 osp_ver=593 osp_pg=0 (spawn #333624) 2025-06-29 06:00:02.044891 :AB170617:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 06:00:02.044892 :AB170618:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=179 time=4152545235 2025-06-29 06:00:02.044893 :AB170619:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 06:00:02.044894 :AB17061A:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 06:00:02.044894 :AB17061B:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 06:00:02.044895 :AB17061C:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 06:00:02.044895 :AB17061D:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 06:00:02.045506 :AB170626:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 12024, J005)