Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906140346\orcl_j002_12488_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:35005M/63366M, Ph+PgF:39848M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 12488, image: ORACLE.EXE (J002) *** 2025-09-06 14:03:46.223 *** SESSION ID:(225.1999) 2025-09-06 14:03:46.223 *** 2025-09-06 14:03:46.223 Process diagnostic dump for ORACLE.EXE (J002), OS id=12488, pid: 28, proc_ser: 97, sid: 225, sess_ser: 1999 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12488 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.143307 sec, exc=0.143307 sec, total=0.143307 sec wait times: max=0.500000 sec, heur=4.146949 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510115 sec, exc=0.510115 sec, total=0.510115 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=4 seq_num=5 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493458 sec, exc=0.493458 sec, total=0.493458 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 225 serial 1999 --------------------------------------------------- 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, 14:03:42 - 14:03:46] idle wait at each sample [session created at: 14:03:42] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-06 14:03:46.223 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 12488, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 14:00:01.900460 :C9C9B36B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:00:01.901267 :C9C9B36C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:00:01.910006 :C9C9B36F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:00:01.894 2025-09-06 14:00:01.910008 :C9C9B370:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17424 pso_num=28 pso_serial#=76 2025-09-06 14:00:01.910161 :C9C9B371:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17424 (legacy spawn) 2025-09-06 14:00:01.916595 :C9C9B372:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:00:01.916626 :C9C9B375:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:00:01.916782 :C9C9B37C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:00:01.917560 :C9C9B389:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:00:05.095611 :C9C9B46D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:00:05.082 2025-09-06 14:00:05.095613 :C9C9B46E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20600 pso_num=28 pso_serial#=77 2025-09-06 14:00:05.095751 :C9C9B46F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20600 (legacy spawn) 2025-09-06 14:00:05.102286 :C9C9B470:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:00:05.102459 :C9C9B475:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:00:05.103281 :C9C9B47A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:00:05.111896 :C9C9B47D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:00:05.098 2025-09-06 14:00:05.111898 :C9C9B47E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21212 pso_num=28 pso_serial#=78 2025-09-06 14:00:05.112029 :C9C9B47F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21212 (legacy spawn) 2025-09-06 14:00:05.118527 :C9C9B480:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:00:05.118694 :C9C9B489:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:00:05.119476 :C9C9B48A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:00:16.443623 :C9C9B802:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:00:16.434 2025-09-06 14:00:16.443625 :C9C9B803:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20664 pso_num=28 pso_serial#=79 2025-09-06 14:00:16.443771 :C9C9B804:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20664 (legacy spawn) 2025-09-06 14:00:16.471150 :C9C9B805:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:00:16.471343 :C9C9B80E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:00:16.472279 :C9C9B80F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:04.634400 :C9C9C9CE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:04.621 2025-09-06 14:01:04.634402 :C9C9C9CF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23796 pso_num=28 pso_serial#=80 2025-09-06 14:01:04.634536 :C9C9C9D0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23796 (legacy spawn) 2025-09-06 14:01:04.640974 :C9C9C9D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:04.641161 :C9C9C9DA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:04.642001 :C9C9C9DB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:14.404660 :C9C9CCE8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:14.390 2025-09-06 14:01:14.404662 :C9C9CCE9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21988 pso_num=28 pso_serial#=81 2025-09-06 14:01:14.404797 :C9C9CCEA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21988 (legacy spawn) 2025-09-06 14:01:14.411333 :C9C9CCEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:14.411510 :C9C9CCF4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:14.412340 :C9C9CCF5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:14.421346 :C9C9CCF8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:14.421 2025-09-06 14:01:14.421348 :C9C9CCF9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23200 pso_num=28 pso_serial#=82 2025-09-06 14:01:14.421484 :C9C9CCFA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23200 (legacy spawn) 2025-09-06 14:01:14.428123 :C9C9CCFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:14.428286 :C9C9CD02:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:14.429127 :C9C9CD05:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:17.502514 :C9C9CE21:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:17.489 2025-09-06 14:01:17.502516 :C9C9CE22:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8216 pso_num=28 pso_serial#=83 2025-09-06 14:01:17.502652 :C9C9CE23:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8216 (legacy spawn) 2025-09-06 14:01:17.509182 :C9C9CE24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:17.509351 :C9C9CE2A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:17.510202 :C9C9CE2E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:17.529040 :C9C9CE31:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:17.520 2025-09-06 14:01:17.529042 :C9C9CE32:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20776 pso_num=28 pso_serial#=84 2025-09-06 14:01:17.529180 :C9C9CE33:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20776 (legacy spawn) 2025-09-06 14:01:17.535702 :C9C9CE34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:17.535872 :C9C9CE3C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:17.536689 :C9C9CE3E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:18.616465 :C9C9CE70:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:18.609 2025-09-06 14:01:18.616467 :C9C9CE71:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23396 pso_num=28 pso_serial#=85 2025-09-06 14:01:18.616610 :C9C9CE72:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23396 (legacy spawn) 2025-09-06 14:01:18.623103 :C9C9CE73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:18.623268 :C9C9CE78:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:18.624126 :C9C9CE7D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:50.785787 :C9C9D93F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:50.771 2025-09-06 14:01:50.785789 :C9C9D940:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23368 pso_num=28 pso_serial#=86 2025-09-06 14:01:50.785925 :C9C9D941:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23368 (legacy spawn) 2025-09-06 14:01:50.792589 :C9C9D942:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:50.792774 :C9C9D94B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:50.793642 :C9C9D94C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:50.802989 :C9C9D94F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:50.802 2025-09-06 14:01:50.802992 :C9C9D950:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23016 pso_num=28 pso_serial#=87 2025-09-06 14:01:50.803131 :C9C9D951:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23016 (legacy spawn) 2025-09-06 14:01:50.810063 :C9C9D952:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:50.810244 :C9C9D95B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:50.811159 :C9C9D95C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:54.014564 :C9C9DA55:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:54.006 2025-09-06 14:01:54.014566 :C9C9DA56:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21120 pso_num=28 pso_serial#=88 2025-09-06 14:01:54.014704 :C9C9DA57:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21120 (legacy spawn) 2025-09-06 14:01:54.021225 :C9C9DA58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:54.021417 :C9C9DA5D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:54.022223 :C9C9DA62:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:01:54.030927 :C9C9DA65:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:01:54.022 2025-09-06 14:01:54.030930 :C9C9DA66:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21068 pso_num=28 pso_serial#=89 2025-09-06 14:01:54.031073 :C9C9DA67:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21068 (legacy spawn) 2025-09-06 14:01:54.037634 :C9C9DA68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:01:54.037798 :C9C9DA71:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:01:54.038607 :C9C9DA72:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:02:12.829920 :C9C9E002:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:02:12.820 2025-09-06 14:02:12.829922 :C9C9E003:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22696 pso_num=28 pso_serial#=90 2025-09-06 14:02:12.830063 :C9C9E004:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22696 (legacy spawn) 2025-09-06 14:02:12.836596 :C9C9E005:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:02:12.836761 :C9C9E00A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:02:12.837610 :C9C9E00F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:02:51.862570 :C9C9EDEB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:02:51.851 2025-09-06 14:02:51.862572 :C9C9EDEC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19868 pso_num=28 pso_serial#=91 2025-09-06 14:02:51.862711 :C9C9EDED:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19868 (legacy spawn) 2025-09-06 14:02:51.869201 :C9C9EDEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:02:51.869392 :C9C9EDF3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:02:51.870218 :C9C9EDF8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:03:03.491915 :C9C9F19D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:03:03.477 2025-09-06 14:03:03.491917 :C9C9F19E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4160 pso_num=28 pso_serial#=92 2025-09-06 14:03:03.492053 :C9C9F19F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=4160 (legacy spawn) 2025-09-06 14:03:03.498442 :C9C9F1A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:03.498616 :C9C9F1A5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:03:03.499448 :C9C9F1AA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:03:03.508108 :C9C9F1AD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:03:03.492 2025-09-06 14:03:03.508110 :C9C9F1AE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22372 pso_num=28 pso_serial#=93 2025-09-06 14:03:03.508249 :C9C9F1AF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22372 (legacy spawn) 2025-09-06 14:03:03.514705 :C9C9F1B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:03.514865 :C9C9F1B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:03:03.515651 :C9C9F1BA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:03:06.584140 :C9C9F2B1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:03:06.571 2025-09-06 14:03:06.584142 :C9C9F2B2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19584 pso_num=28 pso_serial#=94 2025-09-06 14:03:06.584282 :C9C9F2B3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19584 (legacy spawn) 2025-09-06 14:03:06.590747 :C9C9F2B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:06.590918 :C9C9F2B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:03:06.591710 :C9C9F2BE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:03:06.611007 :C9C9F2C1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:03:06.602 2025-09-06 14:03:06.611009 :C9C9F2C2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21740 pso_num=28 pso_serial#=95 2025-09-06 14:03:06.611146 :C9C9F2C3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21740 (legacy spawn) 2025-09-06 14:03:06.617694 :C9C9F2C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:06.617853 :C9C9F2CD:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 14:03:06.618654 :C9C9F2CE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:03:41.872609 :C9C9FE5F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:03:41.869 2025-09-06 14:03:41.872610 :C9C9FE60:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23684 pso_num=28 pso_serial#=96 2025-09-06 14:03:41.872742 :C9C9FE61:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23684 osp_idx=26 osp_ver=295689 osp_pg=0 (spawn #564725) 2025-09-06 14:03:41.872742 :C9C9FE62:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 14:03:41.872743 :C9C9FE63:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=45 time=1553073158 2025-09-06 14:03:41.872743 :C9C9FE64:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 14:03:41.872744 :C9C9FE65:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 14:03:41.872744 :C9C9FE66:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 14:03:41.872744 :C9C9FE67:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 14:03:41.872745 :C9C9FE68:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 14:03:41.873362 :C9C9FE69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.873427 :C9C9FE6F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 14:03:41.873500 :C9C9FE7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.877930 :C9C9FE86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.934751 :C9C9FEB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.936187 :C9C9FEBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.936716 :C9C9FEC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.936737 :C9C9FEC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.936781 :C9C9FED4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 14:03:41.937744 :C9C9FEDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.937762 :C9C9FEE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.937790 :C9C9FEED:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 14:03:41.938230 :C9C9FEF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.940612 :C9C9FF00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.945657 :C9C9FF08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.950226 :C9C9FF10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.951172 :C9C9FF18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.952378 :C9C9FF20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.955229 :C9C9FF28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.956264 :C9C9FF30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.956272 :C9C9FF31:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0006.000.0000cae4 2025-09-06 14:03:41.959346 :C9C9FF39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.959500 :C9C9FF48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.959751 :C9C9FF53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.959794 :C9C9FF56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-06 14:03:41.959806 :C9C9FF57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.959814 :C9C9FF59:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-06 14:03:41.962392 :C9C9FF6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.962498 :C9C9FF7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.962705 :C9C9FF80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.962748 :C9C9FF83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-06 14:03:41.962763 :C9C9FF84:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-06 14:03:41.962959 :C9C9FF8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.962971 :C9C9FF8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-06 14:03:41.963000 :C9C9FF98:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-06 14:03:41.965010 :C9C9FFA2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-06 14:03:42.077490 :C9CA02F1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-06 14:03:42.072 2025-09-06 14:03:42.077492 :C9CA02F2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12488 pso_num=28 pso_serial#=97 2025-09-06 14:03:42.077670 :C9CA02F3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12488 osp_idx=26 osp_ver=295690 osp_pg=0 (spawn #564729) 2025-09-06 14:03:42.077671 :C9CA02F4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 14:03:42.077671 :C9CA02F5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=46 time=1553073377 2025-09-06 14:03:42.077671 :C9CA02F6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 14:03:42.077673 :C9CA02F7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 14:03:42.077674 :C9CA02F8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 14:03:42.077674 :C9CA02F9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 14:03:42.077674 :C9CA02FA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 14:03:42.078233 :C9CA0304:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 12488, J002)