Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250608220917\orcl_j002_12904_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:36804M/63366M, Ph+PgF:43438M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 12904, image: ORACLE.EXE (J002) *** 2025-06-08 22:09:17.822 *** SESSION ID:(219.42791) 2025-06-08 22:09:17.822 *** 2025-06-08 22:09:17.822 Process diagnostic dump for ORACLE.EXE (J002), OS id=12904, pid: 27, proc_ser: 63, sid: 219, sess_ser: 42791 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12904 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.313801 sec, exc=0.313801 sec, total=0.313801 sec wait times: max=0.500000 sec, heur=8.308897 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.000016 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.500102 sec, exc=0.500102 sec, total=0.500102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499978 sec, exc=0.499978 sec, total=0.499978 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500020 sec, exc=0.500020 sec, total=0.500020 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500114 sec, exc=0.500114 sec, total=0.500114 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time Sampled Session History of session 219 serial 42791 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:09:09 - 22:09:17] idle wait at each sample [session created at: 22:09:09] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-08 22:09:17.822 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 12904, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-08 22:05:13.206002 :A21FB54C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:05:13.214779 :A21FB54F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:05:13.200 2025-06-08 22:05:13.214781 :A21FB550:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13372 pso_num=27 pso_serial#=39 2025-06-08 22:05:13.214918 :A21FB551:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13372 (legacy spawn) 2025-06-08 22:05:13.221448 :A21FB55E: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-08 22:05:13.221625 :A21FB566: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-08 22:05:13.222433 :A21FB567:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:12.985026 :A21FCE37:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:12.971 2025-06-08 22:06:12.985028 :A21FCE38:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6832 pso_num=27 pso_serial#=40 2025-06-08 22:06:12.985163 :A21FCE39:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6832 (legacy spawn) 2025-06-08 22:06:12.991693 :A21FCE3A: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-08 22:06:12.991878 :A21FCE3F: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-08 22:06:12.992684 :A21FCE44:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:15.390949 :A21FCF32:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:15.378 2025-06-08 22:06:15.390951 :A21FCF33:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13340 pso_num=27 pso_serial#=41 2025-06-08 22:06:15.391088 :A21FCF34:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13340 (legacy spawn) 2025-06-08 22:06:15.397524 :A21FCF35: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-08 22:06:15.397695 :A21FCF3A: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-08 22:06:15.398494 :A21FCF3F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:22.609735 :A21FD13A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:22.599 2025-06-08 22:06:22.609737 :A21FD13B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14652 pso_num=27 pso_serial#=42 2025-06-08 22:06:22.609876 :A21FD13C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14652 (legacy spawn) 2025-06-08 22:06:22.616319 :A21FD13D: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-08 22:06:22.616511 :A21FD142: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-08 22:06:22.617295 :A21FD147:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:22.625963 :A21FD14A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:22.614 2025-06-08 22:06:22.625966 :A21FD14B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8164 pso_num=27 pso_serial#=43 2025-06-08 22:06:22.626107 :A21FD14C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8164 (legacy spawn) 2025-06-08 22:06:22.632611 :A21FD14D: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-08 22:06:22.632787 :A21FD156: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-08 22:06:22.633588 :A21FD157:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:25.791134 :A21FD25C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:25.778 2025-06-08 22:06:25.791136 :A21FD25D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16268 pso_num=27 pso_serial#=44 2025-06-08 22:06:25.791276 :A21FD25E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16268 (legacy spawn) 2025-06-08 22:06:25.797777 :A21FD25F: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-08 22:06:25.797946 :A21FD268: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-08 22:06:25.798740 :A21FD269:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:25.815900 :A21FD26C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:25.809 2025-06-08 22:06:25.815902 :A21FD26D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15536 pso_num=27 pso_serial#=45 2025-06-08 22:06:25.816036 :A21FD26E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15536 (legacy spawn) 2025-06-08 22:06:25.822565 :A21FD26F: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-08 22:06:25.822727 :A21FD278: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-08 22:06:25.823518 :A21FD279:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:32.092478 :A21FD43B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:32.084 2025-06-08 22:06:32.092479 :A21FD43C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12492 pso_num=27 pso_serial#=46 2025-06-08 22:06:32.092614 :A21FD43D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12492 (legacy spawn) 2025-06-08 22:06:32.099067 :A21FD43E: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-08 22:06:32.099258 :A21FD447: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-08 22:06:32.100035 :A21FD448:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:44.064574 :A21FD80C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:44.056 2025-06-08 22:06:44.064576 :A21FD80D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14240 pso_num=27 pso_serial#=47 2025-06-08 22:06:44.064707 :A21FD80E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14240 (legacy spawn) 2025-06-08 22:06:44.071256 :A21FD80F: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-08 22:06:44.071436 :A21FD818: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-08 22:06:44.072246 :A21FD819:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:58.991394 :A21FDD4D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:58.988 2025-06-08 22:06:58.991396 :A21FDD4E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11852 pso_num=27 pso_serial#=48 2025-06-08 22:06:58.991529 :A21FDD4F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11852 (legacy spawn) 2025-06-08 22:06:59.008312 :A21FDD50: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-08 22:06:59.008519 :A21FDD55: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-08 22:06:59.009315 :A21FDD5A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:06:59.018064 :A21FDD5D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:06:59.003 2025-06-08 22:06:59.018066 :A21FDD5E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14460 pso_num=27 pso_serial#=49 2025-06-08 22:06:59.018219 :A21FDD5F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14460 (legacy spawn) 2025-06-08 22:06:59.024651 :A21FDD60: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-08 22:06:59.024828 :A21FDD69: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-08 22:06:59.025623 :A21FDD6A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:07:02.188912 :A21FDE4D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:07:02.175 2025-06-08 22:07:02.188914 :A21FDE4E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13080 pso_num=27 pso_serial#=50 2025-06-08 22:07:02.189099 :A21FDE4F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13080 (legacy spawn) 2025-06-08 22:07:02.195636 :A21FDE50: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-08 22:07:02.195805 :A21FDE55: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-08 22:07:02.196610 :A21FDE5A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:07:02.205274 :A21FDE5D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:07:02.191 2025-06-08 22:07:02.205276 :A21FDE5E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15328 pso_num=27 pso_serial#=51 2025-06-08 22:07:02.205412 :A21FDE5F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15328 (legacy spawn) 2025-06-08 22:07:02.211907 :A21FDE60: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-08 22:07:02.212072 :A21FDE69: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-08 22:07:02.212881 :A21FDE6A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:07:15.112941 :A21FE2CD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:07:15.105 2025-06-08 22:07:15.112944 :A21FE2CE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14164 pso_num=27 pso_serial#=52 2025-06-08 22:07:15.113088 :A21FE2CF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14164 (legacy spawn) 2025-06-08 22:07:15.119530 :A21FE2D0: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-08 22:07:15.119698 :A21FE2D5: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-08 22:07:15.120510 :A21FE2DA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:07:57.850128 :A21FF035:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:07:57.836 2025-06-08 22:07:57.850131 :A21FF036:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15440 pso_num=27 pso_serial#=53 2025-06-08 22:07:57.850261 :A21FF037:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15440 (legacy spawn) 2025-06-08 22:07:57.856784 :A21FF038: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-08 22:07:57.856956 :A21FF03D: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-08 22:07:57.857750 :A21FF042:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:07:58.115903 :A21FF045:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:07:58.102 2025-06-08 22:07:58.115905 :A21FF046:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10716 pso_num=27 pso_serial#=54 2025-06-08 22:07:58.116045 :A21FF047:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10716 (legacy spawn) 2025-06-08 22:07:58.122483 :A21FF048: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-08 22:07:58.122653 :A21FF051: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-08 22:07:58.123442 :A21FF052:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:07:58.132026 :A21FF055:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:07:58.118 2025-06-08 22:07:58.132028 :A21FF056:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16088 pso_num=27 pso_serial#=55 2025-06-08 22:07:58.132163 :A21FF057:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16088 (legacy spawn) 2025-06-08 22:07:58.138635 :A21FF058: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-08 22:07:58.138796 :A21FF061: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-08 22:07:58.139577 :A21FF062:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:08:11.682631 :A21FF4A1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:08:11.667 2025-06-08 22:08:11.682633 :A21FF4A2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14312 pso_num=27 pso_serial#=56 2025-06-08 22:08:11.682774 :A21FF4A3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14312 (legacy spawn) 2025-06-08 22:08:11.689136 :A21FF4A4: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-08 22:08:11.689329 :A21FF4A9: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-08 22:08:11.690134 :A21FF4AE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:08:11.708031 :A21FF4B1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:08:11.699 2025-06-08 22:08:11.708033 :A21FF4B2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12936 pso_num=27 pso_serial#=57 2025-06-08 22:08:11.708177 :A21FF4B3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12936 (legacy spawn) 2025-06-08 22:08:11.714625 :A21FF4B4: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-08 22:08:11.714782 :A21FF4BD: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-08 22:08:11.715580 :A21FF4BE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:08:14.885929 :A21FF5D2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:08:14.873 2025-06-08 22:08:14.885931 :A21FF5D3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13880 pso_num=27 pso_serial#=58 2025-06-08 22:08:14.886072 :A21FF5D4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13880 (legacy spawn) 2025-06-08 22:08:14.892560 :A21FF5D5: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-08 22:08:14.892736 :A21FF5DA: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-08 22:08:14.893517 :A21FF5DF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:08:14.902168 :A21FF5E2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:08:14.888 2025-06-08 22:08:14.902171 :A21FF5E3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12656 pso_num=27 pso_serial#=59 2025-06-08 22:08:14.902309 :A21FF5E4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12656 (legacy spawn) 2025-06-08 22:08:14.908787 :A21FF5E5: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-08 22:08:14.908818 :A21FF5E8: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-08 22:08:14.908954 :A21FF5EF: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-08 22:08:14.909769 :A21FF5FC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:08:17.261018 :A21FF658:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:08:17.253 2025-06-08 22:08:17.261020 :A21FF659:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12576 pso_num=27 pso_serial#=60 2025-06-08 22:08:17.261154 :A21FF65A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12576 (legacy spawn) 2025-06-08 22:08:17.288648 :A21FF65B: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-08 22:08:17.288834 :A21FF663: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-08 22:08:17.289661 :A21FF665:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:09:05.874247 :A22006F8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:09:05.861 2025-06-08 22:09:05.874249 :A22006F9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13000 pso_num=27 pso_serial#=61 2025-06-08 22:09:05.874400 :A22006FA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13000 (legacy spawn) 2025-06-08 22:09:05.881020 :A22006FB: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-08 22:09:05.881198 :A2200700: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-08 22:09:05.882035 :A2200705:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:09:09.310616 :A2200850:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:09:09.305 2025-06-08 22:09:09.310619 :A2200851:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16320 pso_num=27 pso_serial#=62 2025-06-08 22:09:09.310756 :A2200852:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16320 osp_idx=25 osp_ver=54121 osp_pg=0 (spawn #265838) 2025-06-08 22:09:09.310757 :A2200853: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-08 22:09:09.310758 :A2200854:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=184 time=2396292500 2025-06-08 22:09:09.310758 :A2200855:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 22:09:09.310758 :A2200856:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 22:09:09.310758 :A2200857:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 22:09:09.310759 :A2200858:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 22:09:09.310759 :A2200859:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 22:09:09.311250 :A220085A: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-08 22:09:09.311297 :A2200861: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-08 22:09:09.315520 :A2200877:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-08 22:09:09.315564 :A2200878:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-08 22:09:09.315818 :A2200879:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-08 22:09:09.315834 :A220087A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-08 22:09:09.316049 :A220087B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-08 22:09:09.316063 :A220087C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-08 22:09:09.316276 :A220087D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-08 22:09:09.316297 :A220087E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-08 22:09:09.316585 :A220087F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-08 22:09:09.316598 :A2200880:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-08 22:09:09.316811 :A2200881:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-08 22:09:09.316825 :A2200882:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-08 22:09:09.319112 :A22008B5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-08 22:09:09.513750 :A2200CAD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-08 22:09:09.508 2025-06-08 22:09:09.513752 :A2200CAE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12904 pso_num=27 pso_serial#=63 2025-06-08 22:09:09.513919 :A2200CAF:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12904 osp_idx=25 osp_ver=54122 osp_pg=0 (spawn #265840) 2025-06-08 22:09:09.513920 :A2200CB0: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-08 22:09:09.513921 :A2200CB1:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=185 time=2396292704 2025-06-08 22:09:09.513921 :A2200CB2:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 22:09:09.513921 :A2200CB3:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 22:09:09.513922 :A2200CB4:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 22:09:09.513923 :A2200CB5:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 22:09:09.513923 :A2200CB6:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 22:09:09.514469 :A2200CB7: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: 12904, J002)