Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614100500\orcl_j002_14524_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:36689M/63366M, Ph+PgF:43179M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 14524, image: ORACLE.EXE (J002) *** 2025-06-14 10:05:00.481 *** SESSION ID:(219.63077) 2025-06-14 10:05:00.481 *** 2025-06-14 10:05:00.481 Process diagnostic dump for ORACLE.EXE (J002), OS id=14524, pid: 27, proc_ser: 254, sid: 219, sess_ser: 63077 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14524 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.422440 sec, exc=0.422440 sec, total=0.422440 sec wait times: max=0.500000 sec, heur=4.426428 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=7 seq_num=8 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 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.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.000007 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.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.493820 sec, exc=0.493820 sec, total=0.493820 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 219 serial 63077 --------------------------------------------------- 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, 10:04:56 - 10:05:00] idle wait at each sample [session created at: 10:04:56] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 10:05:00.481 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 14524, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 09:59:29.596600 :A484E3DC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8672 (legacy spawn) 2025-06-14 09:59:29.603004 :A484E3DD: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-14 09:59:29.603174 :A484E3E3: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-14 09:59:29.604048 :A484E3E6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:00:27.700440 :A484F7B9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:00:27.686 2025-06-14 10:00:27.700442 :A484F7BA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12088 pso_num=27 pso_serial#=228 2025-06-14 10:00:27.700584 :A484F7BB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12088 (legacy spawn) 2025-06-14 10:00:27.707114 :A484F7BC: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-14 10:00:27.707286 :A484F7C1: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-14 10:00:27.708094 :A484F7C6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:00:27.716797 :A484F7C9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:00:27.701 2025-06-14 10:00:27.716798 :A484F7CA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16516 pso_num=27 pso_serial#=229 2025-06-14 10:00:27.716939 :A484F7CB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16516 (legacy spawn) 2025-06-14 10:00:27.723400 :A484F7CC: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-14 10:00:27.723566 :A484F7D5: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-14 10:00:27.724358 :A484F7D6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:00:28.523473 :A484F820:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:00:28.514 2025-06-14 10:00:28.523476 :A484F821:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17152 pso_num=27 pso_serial#=230 2025-06-14 10:00:28.523611 :A484F822:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17152 (legacy spawn) 2025-06-14 10:00:28.530092 :A484F823: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-14 10:00:28.530253 :A484F82C: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-14 10:00:28.531053 :A484F82D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:00:30.805956 :A484F8CA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:00:30.797 2025-06-14 10:00:30.805958 :A484F8CB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14052 pso_num=27 pso_serial#=231 2025-06-14 10:00:30.806103 :A484F8CC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14052 (legacy spawn) 2025-06-14 10:00:30.812562 :A484F8CD: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-14 10:00:30.812740 :A484F8D2: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-14 10:00:30.813671 :A484F8F0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:00:30.822413 :A484F8F3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:00:30.813 2025-06-14 10:00:30.822415 :A484F8F4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9092 pso_num=27 pso_serial#=232 2025-06-14 10:00:30.822555 :A484F8F5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9092 (legacy spawn) 2025-06-14 10:00:30.829054 :A484F8F6: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-14 10:00:30.829209 :A484F8FF: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-14 10:00:30.830013 :A484F900:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:00:31.790267 :A484F945:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:00:31.782 2025-06-14 10:00:31.790269 :A484F946:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16168 pso_num=27 pso_serial#=233 2025-06-14 10:00:31.790408 :A484F947:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16168 (legacy spawn) 2025-06-14 10:00:31.796852 :A484F948: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-14 10:00:31.797022 :A484F951: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-14 10:00:31.797863 :A484F952:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:01:33.989320 :A4850EAB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:01:33.981 2025-06-14 10:01:33.989322 :A4850EAC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15516 pso_num=27 pso_serial#=234 2025-06-14 10:01:33.989455 :A4850EAD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15516 (legacy spawn) 2025-06-14 10:01:33.995901 :A4850EAE: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-14 10:01:33.996071 :A4850EB3: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-14 10:01:33.996914 :A4850EB8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:01:35.983785 :A4850F33:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:01:35.970 2025-06-14 10:01:35.983787 :A4850F34:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16104 pso_num=27 pso_serial#=235 2025-06-14 10:01:35.983921 :A4850F35:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16104 (legacy spawn) 2025-06-14 10:01:35.990664 :A4850F36: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-14 10:01:35.990846 :A4850F3B: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-14 10:01:35.991854 :A4850F40:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:01:36.001215 :A4850F43:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:01:36.001 2025-06-14 10:01:36.001217 :A4850F44:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14984 pso_num=27 pso_serial#=236 2025-06-14 10:01:36.001370 :A4850F45:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14984 (legacy spawn) 2025-06-14 10:01:36.008648 :A4850F46: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-14 10:01:36.008836 :A4850F4F: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-14 10:01:36.009741 :A4850F50:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:01:36.670153 :A4850F86:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:01:36.657 2025-06-14 10:01:36.670155 :A4850F87:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11908 pso_num=27 pso_serial#=237 2025-06-14 10:01:36.670289 :A4850F88:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11908 (legacy spawn) 2025-06-14 10:01:36.676774 :A4850F89: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-14 10:01:36.676944 :A4850F8E: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-14 10:01:36.677747 :A4850F93:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:08.173696 :A4851B0C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:08.166 2025-06-14 10:02:08.173698 :A4851B0D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16264 pso_num=27 pso_serial#=238 2025-06-14 10:02:08.173828 :A4851B0E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16264 (legacy spawn) 2025-06-14 10:02:08.180388 :A4851B0F: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-14 10:02:08.180420 :A4851B12: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-14 10:02:08.180568 :A4851B19: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-14 10:02:08.181374 :A4851B26:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:10.731462 :A4851BF7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:10.717 2025-06-14 10:02:10.731464 :A4851BF8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16712 pso_num=27 pso_serial#=239 2025-06-14 10:02:10.731615 :A4851BF9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16712 (legacy spawn) 2025-06-14 10:02:10.738238 :A4851BFA: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-14 10:02:10.738415 :A4851BFF: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-14 10:02:10.739228 :A4851C04:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:16.549992 :A4851DD5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:16.536 2025-06-14 10:02:16.549994 :A4851DD6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15784 pso_num=27 pso_serial#=240 2025-06-14 10:02:16.550129 :A4851DD7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15784 (legacy spawn) 2025-06-14 10:02:16.556580 :A4851DD8: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-14 10:02:16.556758 :A4851DDD: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-14 10:02:16.557540 :A4851DE2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:16.566211 :A4851DE5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:16.552 2025-06-14 10:02:16.566213 :A4851DE6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16208 pso_num=27 pso_serial#=241 2025-06-14 10:02:16.566346 :A4851DE7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16208 (legacy spawn) 2025-06-14 10:02:16.572773 :A4851DE8: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-14 10:02:16.572946 :A4851DF1: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-14 10:02:16.573730 :A4851DF2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:19.705045 :A4851EE3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:19.692 2025-06-14 10:02:19.705047 :A4851EE4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14980 pso_num=27 pso_serial#=242 2025-06-14 10:02:19.705178 :A4851EE5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14980 (legacy spawn) 2025-06-14 10:02:19.711689 :A4851EE6: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-14 10:02:19.711858 :A4851EEB: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-14 10:02:19.712643 :A4851EF0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:19.721303 :A4851EF3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:19.708 2025-06-14 10:02:19.721306 :A4851EF4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14692 pso_num=27 pso_serial#=243 2025-06-14 10:02:19.721440 :A4851EF5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14692 (legacy spawn) 2025-06-14 10:02:19.727922 :A4851EF6: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-14 10:02:19.728107 :A4851EFF: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-14 10:02:19.728914 :A4851F00:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:02:36.135489 :A4852417:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:02:36.127 2025-06-14 10:02:36.135492 :A4852418:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14328 pso_num=27 pso_serial#=244 2025-06-14 10:02:36.135642 :A4852419:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14328 (legacy spawn) 2025-06-14 10:02:36.142320 :A485241A: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-14 10:02:36.142560 :A485241F: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-14 10:02:36.143491 :A4852424:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:03:18.865820 :A4853224:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:03:18.856 2025-06-14 10:03:18.865822 :A4853225:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17048 pso_num=27 pso_serial#=245 2025-06-14 10:03:18.865960 :A4853226:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17048 (legacy spawn) 2025-06-14 10:03:18.872434 :A4853227: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-14 10:03:18.872603 :A485322C: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-14 10:03:18.873376 :A4853231:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:03:29.470413 :A485357C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:03:29.456 2025-06-14 10:03:29.470415 :A485357D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13424 pso_num=27 pso_serial#=246 2025-06-14 10:03:29.470550 :A485357E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13424 (legacy spawn) 2025-06-14 10:03:29.477022 :A485357F: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-14 10:03:29.477193 :A4853588: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-14 10:03:29.477953 :A4853589:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:03:29.486711 :A485358C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:03:29.472 2025-06-14 10:03:29.486713 :A485358D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16304 pso_num=27 pso_serial#=247 2025-06-14 10:03:29.486849 :A485358E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16304 (legacy spawn) 2025-06-14 10:03:29.493370 :A485358F: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-14 10:03:29.493540 :A4853598: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-14 10:03:29.494381 :A4853599:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:03:32.579074 :A485367F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:03:32.565 2025-06-14 10:03:32.579077 :A4853680:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16212 pso_num=27 pso_serial#=248 2025-06-14 10:03:32.579216 :A4853681:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16212 (legacy spawn) 2025-06-14 10:03:32.585746 :A4853682: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-14 10:03:32.585923 :A4853687: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-14 10:03:32.586710 :A485368C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:03:32.605486 :A485368F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:03:32.597 2025-06-14 10:03:32.605488 :A4853690:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8432 pso_num=27 pso_serial#=249 2025-06-14 10:03:32.605625 :A4853691:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8432 (legacy spawn) 2025-06-14 10:03:32.612030 :A4853692: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-14 10:03:32.612190 :A485369B: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-14 10:03:32.613073 :A48536A4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:04:08.842787 :A485425B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:04:08.829 2025-06-14 10:04:08.842789 :A485425C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15000 pso_num=27 pso_serial#=250 2025-06-14 10:04:08.842924 :A485425D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15000 (legacy spawn) 2025-06-14 10:04:08.849395 :A485425E: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-14 10:04:08.849573 :A4854267: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-14 10:04:08.850400 :A4854268:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:04:08.859160 :A485426B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:04:08.845 2025-06-14 10:04:08.859162 :A485426C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12136 pso_num=27 pso_serial#=251 2025-06-14 10:04:08.859302 :A485426D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12136 (legacy spawn) 2025-06-14 10:04:08.865791 :A485426E: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-14 10:04:08.865961 :A4854277: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-14 10:04:08.866757 :A4854278:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:04:27.149169 :A4854824:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:04:27.140 2025-06-14 10:04:27.149171 :A4854825:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14976 pso_num=27 pso_serial#=252 2025-06-14 10:04:27.149310 :A4854826:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14976 (legacy spawn) 2025-06-14 10:04:27.155754 :A4854827: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-14 10:04:27.155918 :A485482C: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-14 10:04:27.156711 :A4854831:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:04:55.848883 :A48551B7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:04:55.846 2025-06-14 10:04:55.848884 :A48551B8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13660 pso_num=27 pso_serial#=253 2025-06-14 10:04:55.849042 :A48551B9:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13660 osp_idx=25 osp_ver=64257 osp_pg=0 (spawn #284166) 2025-06-14 10:04:55.849043 :A48551BA: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-14 10:04:55.849044 :A48551BB:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=54 time=2871239032 2025-06-14 10:04:55.849044 :A48551BC:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 10:04:55.849045 :A48551BD:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 10:04:55.849046 :A48551BE:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 10:04:55.849046 :A48551BF:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 10:04:55.849047 :A48551C0:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 10:04:55.849673 :A48551C1: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-14 10:04:55.849728 :A48551C7: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-14 10:04:55.857776 :A48551D9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 10:04:56.054208 :A485561B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 10:04:56.049 2025-06-14 10:04:56.054210 :A485561C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14524 pso_num=27 pso_serial#=254 2025-06-14 10:04:56.054339 :A485561D:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14524 osp_idx=25 osp_ver=64258 osp_pg=0 (spawn #284169) 2025-06-14 10:04:56.054340 :A485561E: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-14 10:04:56.054340 :A485561F:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=55 time=2871239250 2025-06-14 10:04:56.054341 :A4855620:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 10:04:56.054341 :A4855621:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 10:04:56.054341 :A4855622:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 10:04:56.054342 :A4855623:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 10:04:56.054342 :A4855624:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 10:04:56.054838 :A485562E: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: 14524, J002)