Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250628180742\orcl_j002_17436_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:36371M/63366M, Ph+PgF:42462M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 17436, image: ORACLE.EXE (J002) *** 2025-06-28 18:07:42.693 *** SESSION ID:(219.24373) 2025-06-28 18:07:42.693 *** 2025-06-28 18:07:42.693 Process diagnostic dump for ORACLE.EXE (J002), OS id=17436, pid: 27, proc_ser: 242, sid: 219, sess_ser: 24373 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17436 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.344001 sec, exc=0.344001 sec, total=0.344001 sec wait times: max=0.500000 sec, heur=6.863917 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=12 seq_num=13 snap_id=1 wait times: snap=0.507334 sec, exc=0.507334 sec, total=0.507334 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=11 seq_num=12 snap_id=1 wait times: snap=0.500104 sec, exc=0.500104 sec, total=0.500104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 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.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.000009 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.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000007 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.500143 sec, exc=0.500143 sec, total=0.500143 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=4 seq_num=5 snap_id=1 wait times: snap=0.508301 sec, exc=0.508301 sec, total=0.508301 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=3 seq_num=4 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.000014 sec of elapsed time Sampled Session History of session 219 serial 24373 --------------------------------------------------- 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, 18:07:35 - 18:07:42] idle wait at each sample [session created at: 18:07:35] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-28 18:07:42.693 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 17436, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-28 18:03:54.435723 :AADE9E26: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 18:03:54.436568 :AADE9E28:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:03:57.521566 :AADE9F20:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:03:57.508 2025-06-28 18:03:57.521569 :AADE9F21:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12040 pso_num=27 pso_serial#=218 2025-06-28 18:03:57.521702 :AADE9F22:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12040 (legacy spawn) 2025-06-28 18:03:57.528211 :AADE9F23: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 18:03:57.528391 :AADE9F28: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 18:03:57.529190 :AADE9F2D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:03:57.537798 :AADE9F30:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:03:57.523 2025-06-28 18:03:57.537800 :AADE9F31:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4624 pso_num=27 pso_serial#=219 2025-06-28 18:03:57.537939 :AADE9F32:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4624 (legacy spawn) 2025-06-28 18:03:57.544397 :AADE9F33: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 18:03:57.544560 :AADE9F3C: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 18:03:57.545388 :AADE9F3D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:04:30.720566 :AADEAA1A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:04:30.709 2025-06-28 18:04:30.720568 :AADEAA1B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15420 pso_num=27 pso_serial#=220 2025-06-28 18:04:30.720714 :AADEAA1C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15420 (legacy spawn) 2025-06-28 18:04:30.727525 :AADEAA1D: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 18:04:30.727711 :AADEAA22: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 18:04:30.728554 :AADEAA27:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:04:30.737619 :AADEAA2A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:04:30.724 2025-06-28 18:04:30.737620 :AADEAA2B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18164 pso_num=27 pso_serial#=221 2025-06-28 18:04:30.737809 :AADEAA2C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18164 (legacy spawn) 2025-06-28 18:04:30.744663 :AADEAA2D: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 18:04:30.744834 :AADEAA36: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 18:04:30.745759 :AADEAA37:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:04:33.888626 :AADEAB23:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:04:33.875 2025-06-28 18:04:33.888628 :AADEAB24:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14948 pso_num=27 pso_serial#=222 2025-06-28 18:04:33.888771 :AADEAB25:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14948 (legacy spawn) 2025-06-28 18:04:33.895307 :AADEAB26: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 18:04:33.895474 :AADEAB2B: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 18:04:33.896271 :AADEAB30:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:04:33.904855 :AADEAB33:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:04:33.891 2025-06-28 18:04:33.904856 :AADEAB34:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18208 pso_num=27 pso_serial#=223 2025-06-28 18:04:33.904993 :AADEAB35:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18208 (legacy spawn) 2025-06-28 18:04:33.923899 :AADEAB36: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 18:04:33.924085 :AADEAB3F: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 18:04:33.924933 :AADEAB40:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:04:47.046588 :AADEAF20:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:04:47.037 2025-06-28 18:04:47.046590 :AADEAF21:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16300 pso_num=27 pso_serial#=224 2025-06-28 18:04:47.046780 :AADEAF22:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16300 (legacy spawn) 2025-06-28 18:04:47.053525 :AADEAF23: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 18:04:47.053697 :AADEAF2C: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 18:04:47.054533 :AADEAF2D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:04:57.820804 :AADEB2CA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:04:57.811 2025-06-28 18:04:57.820806 :AADEB2CB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17236 pso_num=27 pso_serial#=225 2025-06-28 18:04:57.820953 :AADEB2CC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17236 (legacy spawn) 2025-06-28 18:04:57.827794 :AADEB2CD: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 18:04:57.827964 :AADEB2D2: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 18:04:57.828848 :AADEB2D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:05:43.415921 :AADEC8CF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:05:43.408 2025-06-28 18:05:43.415923 :AADEC8D0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7348 pso_num=27 pso_serial#=226 2025-06-28 18:05:43.416067 :AADEC8D1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7348 (legacy spawn) 2025-06-28 18:05:43.422576 :AADEC8D2: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 18:05:43.422758 :AADEC8D7: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 18:05:43.423572 :AADEC8DC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:05:43.432447 :AADEC8DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:05:43.424 2025-06-28 18:05:43.432449 :AADEC8E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15772 pso_num=27 pso_serial#=227 2025-06-28 18:05:43.432588 :AADEC8E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15772 (legacy spawn) 2025-06-28 18:05:43.439140 :AADEC8E2: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 18:05:43.439318 :AADEC8EB: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 18:05:43.440149 :AADEC8EC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:05:46.707379 :AADEC9F0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:05:46.694 2025-06-28 18:05:46.707381 :AADEC9F1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18124 pso_num=27 pso_serial#=228 2025-06-28 18:05:46.707516 :AADEC9F2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18124 (legacy spawn) 2025-06-28 18:05:46.713997 :AADEC9F3: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 18:05:46.714188 :AADEC9F8: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 18:05:46.715009 :AADEC9FD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:05:46.723595 :AADECA00:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:05:46.710 2025-06-28 18:05:46.723597 :AADECA01:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14692 pso_num=27 pso_serial#=229 2025-06-28 18:05:46.723729 :AADECA02:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14692 (legacy spawn) 2025-06-28 18:05:46.730167 :AADECA03: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 18:05:46.730329 :AADECA0C: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 18:05:46.731129 :AADECA0D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:05:55.241649 :AADECCD7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:05:55.232 2025-06-28 18:05:55.241651 :AADECCD8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8772 pso_num=27 pso_serial#=230 2025-06-28 18:05:55.241782 :AADECCD9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8772 (legacy spawn) 2025-06-28 18:05:55.248239 :AADECCDA: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 18:05:55.248433 :AADECCDF: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 18:05:55.249256 :AADECCE4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:30.595851 :AADED86A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:30.586 2025-06-28 18:06:30.595853 :AADED86B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7000 pso_num=27 pso_serial#=231 2025-06-28 18:06:30.595990 :AADED86C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7000 (legacy spawn) 2025-06-28 18:06:30.602457 :AADED86D: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 18:06:30.602623 :AADED872: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 18:06:30.603430 :AADED877:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:31.407759 :AADED8C5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:31.399 2025-06-28 18:06:31.407761 :AADED8C6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6104 pso_num=27 pso_serial#=232 2025-06-28 18:06:31.407898 :AADED8C7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6104 (legacy spawn) 2025-06-28 18:06:31.414297 :AADED8C8: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 18:06:31.414472 :AADED8D1: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 18:06:31.415316 :AADED8D2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:36.507383 :AADEDA6E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:36.499 2025-06-28 18:06:36.507385 :AADEDA6F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10520 pso_num=27 pso_serial#=233 2025-06-28 18:06:36.507529 :AADEDA70:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10520 (legacy spawn) 2025-06-28 18:06:36.513916 :AADEDA71: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 18:06:36.514090 :AADEDA79: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 18:06:36.514908 :AADEDA7B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:56.593899 :AADEE0BE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:56.580 2025-06-28 18:06:56.593901 :AADEE0BF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7020 pso_num=27 pso_serial#=234 2025-06-28 18:06:56.594036 :AADEE0C0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7020 (legacy spawn) 2025-06-28 18:06:56.600619 :AADEE0C1: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 18:06:56.600797 :AADEE0CA: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 18:06:56.601655 :AADEE0CB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:56.619870 :AADEE0CE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:56.611 2025-06-28 18:06:56.619873 :AADEE0CF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10208 pso_num=27 pso_serial#=235 2025-06-28 18:06:56.620016 :AADEE0D0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10208 (legacy spawn) 2025-06-28 18:06:56.626719 :AADEE0D1: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 18:06:56.626902 :AADEE0D6: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 18:06:56.627831 :AADEE0DB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:59.692023 :AADEE1C4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:59.678 2025-06-28 18:06:59.692025 :AADEE1C5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16500 pso_num=27 pso_serial#=236 2025-06-28 18:06:59.692173 :AADEE1C6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16500 (legacy spawn) 2025-06-28 18:06:59.698965 :AADEE1C7: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 18:06:59.699145 :AADEE1CF: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 18:06:59.700005 :AADEE1D1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:06:59.708924 :AADEE1D4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:06:59.694 2025-06-28 18:06:59.708926 :AADEE1D5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1852 pso_num=27 pso_serial#=237 2025-06-28 18:06:59.709102 :AADEE1D6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1852 (legacy spawn) 2025-06-28 18:06:59.715813 :AADEE1D7: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 18:06:59.715986 :AADEE1E0: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 18:06:59.716865 :AADEE1E1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:07:33.097526 :AADEEFC5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:07:33.095 2025-06-28 18:07:33.097528 :AADEEFC6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18176 pso_num=27 pso_serial#=238 2025-06-28 18:07:33.097671 :AADEEFC7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18176 (legacy spawn) 2025-06-28 18:07:33.104105 :AADEEFC8: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 18:07:33.104296 :AADEEFCD: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 18:07:33.105106 :AADEEFD2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:07:33.113833 :AADEEFD5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:07:33.110 2025-06-28 18:07:33.113835 :AADEEFD6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6736 pso_num=27 pso_serial#=239 2025-06-28 18:07:33.113970 :AADEEFD7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6736 (legacy spawn) 2025-06-28 18:07:33.127246 :AADEEFD8: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 18:07:33.127422 :AADEEFE1: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 18:07:33.128266 :AADEEFE2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:07:33.540054 :AADEF023:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:07:33.532 2025-06-28 18:07:33.540056 :AADEF024:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16236 pso_num=27 pso_serial#=240 2025-06-28 18:07:33.540194 :AADEF025:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16236 (legacy spawn) 2025-06-28 18:07:33.546715 :AADEF026: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 18:07:33.546889 :AADEF02F: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 18:07:33.547727 :AADEF030:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:07:35.637575 :AADEF0E5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:07:35.636 2025-06-28 18:07:35.637577 :AADEF0E6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1020 pso_num=27 pso_serial#=241 2025-06-28 18:07:35.637710 :AADEF0E7:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=1020 osp_idx=25 osp_ver=110409 osp_pg=0 (spawn #331970) 2025-06-28 18:07:35.637711 :AADEF0E8:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-28 18:07:35.637711 :AADEF0E9:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=172 time=4109798829 2025-06-28 18:07:35.637712 :AADEF0EA:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 18:07:35.637713 :AADEF0EB:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 18:07:35.637713 :AADEF0EC:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 18:07:35.637714 :AADEF0ED:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 18:07:35.637714 :AADEF0EE:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 18:07:35.638205 :AADEF0EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-28 18:07:35.638252 :AADEF0F5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-28 18:07:35.642428 :AADEF10C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 18:07:35.642473 :AADEF10D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 18:07:35.642731 :AADEF10E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 18:07:35.642748 :AADEF10F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 18:07:35.642972 :AADEF110:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 18:07:35.642991 :AADEF111:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 18:07:35.643281 :AADEF112:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 18:07:35.643294 :AADEF113:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 18:07:35.643511 :AADEF114:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 18:07:35.643524 :AADEF115:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 18:07:35.643742 :AADEF116:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 18:07:35.643756 :AADEF117:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 18:07:35.646050 :AADEF14A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 18:07:35.829028 :AADEF2BA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 18:07:35.823 2025-06-28 18:07:35.829029 :AADEF2BB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17436 pso_num=27 pso_serial#=242 2025-06-28 18:07:35.829165 :AADEF2BC:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17436 osp_idx=25 osp_ver=110410 osp_pg=0 (spawn #331972) 2025-06-28 18:07:35.829165 :AADEF2BD: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 18:07:35.829166 :AADEF2BE:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=173 time=4109799016 2025-06-28 18:07:35.829166 :AADEF2BF:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 18:07:35.829167 :AADEF2C0:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 18:07:35.829167 :AADEF2C1:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 18:07:35.829168 :AADEF2C2:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 18:07:35.829168 :AADEF2C3:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 18:07:35.829652 :AADEF2CD: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 17436, J002)