Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831180708\orcl_j002_23320_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:35081M/63366M, Ph+PgF:40070M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 23320, image: ORACLE.EXE (J002) *** 2025-08-31 18:07:08.908 *** SESSION ID:(225.42139) 2025-08-31 18:07:08.908 *** 2025-08-31 18:07:08.908 Process diagnostic dump for ORACLE.EXE (J002), OS id=23320, pid: 28, proc_ser: 157, sid: 225, sess_ser: 42139 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23320 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.128296 sec, exc=0.128296 sec, total=0.128296 sec wait times: max=0.500000 sec, heur=7.662469 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.510112 sec, exc=0.510112 sec, total=0.510112 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510110 sec, exc=0.510110 sec, total=0.510110 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000010 sec of elapsed time Sampled Session History of session 225 serial 42139 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 18:07:01 - 18:07:08] idle wait at each sample [session created at: 18:07:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 18:07:08.908 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 23320, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 18:02:25.809730 :C7310D5D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20488 (legacy spawn) 2025-08-31 18:02:25.816193 :C7310D5E: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-08-31 18:02:25.816381 :C7310D63: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-08-31 18:02:25.817192 :C7310D67:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:02:25.825950 :C7310D6A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:02:25.822 2025-08-31 18:02:25.825952 :C7310D6B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23076 pso_num=28 pso_serial#=130 2025-08-31 18:02:25.826089 :C7310D6C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23076 (legacy spawn) 2025-08-31 18:02:25.832583 :C7310D6D: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-08-31 18:02:25.832748 :C7310D76: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-08-31 18:02:25.833548 :C7310D77:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:02:28.899526 :C7310E67:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:02:28.886 2025-08-31 18:02:28.899528 :C7310E68:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6008 pso_num=28 pso_serial#=131 2025-08-31 18:02:28.899665 :C7310E69:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6008 (legacy spawn) 2025-08-31 18:02:28.906211 :C7310E6A: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-08-31 18:02:28.906378 :C7310E72: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-08-31 18:02:28.907172 :C7310E74:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:02:28.926681 :C7310E77:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:02:28.917 2025-08-31 18:02:28.926683 :C7310E78:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23160 pso_num=28 pso_serial#=132 2025-08-31 18:02:28.926821 :C7310E79:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23160 (legacy spawn) 2025-08-31 18:02:28.933418 :C7310E7A: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-08-31 18:02:28.933591 :C7310E83: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-08-31 18:02:28.934416 :C7310E84:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:02:38.122761 :C731114B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:02:38.114 2025-08-31 18:02:38.122763 :C731114C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20536 pso_num=28 pso_serial#=133 2025-08-31 18:02:38.122904 :C731114D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20536 (legacy spawn) 2025-08-31 18:02:38.129926 :C731114E: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-08-31 18:02:38.130085 :C7311153: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-08-31 18:02:38.131065 :C7311158:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:03:21.758959 :C7312065:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:03:21.747 2025-08-31 18:03:21.758961 :C7312066:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21392 pso_num=28 pso_serial#=134 2025-08-31 18:03:21.759103 :C7312067:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21392 (legacy spawn) 2025-08-31 18:03:21.765554 :C7312068: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-08-31 18:03:21.765732 :C731206D: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-08-31 18:03:21.766535 :C7312072:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:03:38.607941 :C7312568:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:03:38.593 2025-08-31 18:03:38.607943 :C7312569:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22656 pso_num=28 pso_serial#=135 2025-08-31 18:03:38.608117 :C731256A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22656 (legacy spawn) 2025-08-31 18:03:38.614935 :C731256B: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-08-31 18:03:38.615118 :C7312570: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-08-31 18:03:38.616029 :C7312574:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:03:38.625355 :C7312577:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:03:38.624 2025-08-31 18:03:38.625357 :C7312578:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22184 pso_num=28 pso_serial#=136 2025-08-31 18:03:38.625497 :C7312579:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22184 (legacy spawn) 2025-08-31 18:03:38.644534 :C731257A: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-08-31 18:03:38.644716 :C7312583: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-08-31 18:03:38.645648 :C7312584:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:04:11.352884 :C731301B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:04:11.344 2025-08-31 18:04:11.352885 :C731301C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14400 pso_num=28 pso_serial#=137 2025-08-31 18:04:11.353002 :C731301D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14400 (legacy spawn) 2025-08-31 18:04:11.359521 :C7313033: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-08-31 18:04:11.359671 :C731303C: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-08-31 18:04:11.360441 :C731303D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:04:14.832190 :C7313144:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:04:14.818 2025-08-31 18:04:14.832191 :C7313145:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21444 pso_num=28 pso_serial#=138 2025-08-31 18:04:14.832329 :C7313146:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21444 (legacy spawn) 2025-08-31 18:04:14.838793 :C7313173: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-08-31 18:04:14.838974 :C731317A: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-08-31 18:04:14.839784 :C731317D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:04:14.848632 :C7313180:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:04:14.833 2025-08-31 18:04:14.848634 :C7313181:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18376 pso_num=28 pso_serial#=139 2025-08-31 18:04:14.848775 :C7313182:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18376 (legacy spawn) 2025-08-31 18:04:14.855268 :C7313183: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-08-31 18:04:14.855443 :C731318C: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-08-31 18:04:14.856265 :C731318D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:04:17.996591 :C73132A2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:04:17.994 2025-08-31 18:04:17.996593 :C73132A3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21680 pso_num=28 pso_serial#=140 2025-08-31 18:04:17.996733 :C73132A4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21680 (legacy spawn) 2025-08-31 18:04:18.003208 :C73132A5: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-08-31 18:04:18.003374 :C73132AE: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-08-31 18:04:18.004147 :C73132AF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:04:18.012925 :C73132B2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:04:18.010 2025-08-31 18:04:18.012927 :C73132B3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6128 pso_num=28 pso_serial#=141 2025-08-31 18:04:18.013065 :C73132B4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6128 (legacy spawn) 2025-08-31 18:04:18.027252 :C73132B5: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-08-31 18:04:18.027420 :C73132BA: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-08-31 18:04:18.028271 :C73132BF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:04:30.174972 :C7313668:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:04:30.166 2025-08-31 18:04:30.174974 :C7313669:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22492 pso_num=28 pso_serial#=142 2025-08-31 18:04:30.175126 :C731366A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22492 (legacy spawn) 2025-08-31 18:04:30.181915 :C731366B: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-08-31 18:04:30.182110 :C7313670: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-08-31 18:04:30.182981 :C7313675:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:05:13.541321 :C73144FE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:05:13.532 2025-08-31 18:05:13.541324 :C73144FF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22340 pso_num=28 pso_serial#=143 2025-08-31 18:05:13.541479 :C7314500:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22340 (legacy spawn) 2025-08-31 18:05:13.548568 :C7314501: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-08-31 18:05:13.548772 :C731450A: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-08-31 18:05:13.549675 :C731450B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:05:27.620857 :C7314AC4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:05:27.616 2025-08-31 18:05:27.620859 :C7314AC5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20704 pso_num=28 pso_serial#=144 2025-08-31 18:05:27.621028 :C7314AC6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20704 (legacy spawn) 2025-08-31 18:05:27.627986 :C7314AC7: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-08-31 18:05:27.628192 :C7314ACC: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-08-31 18:05:27.629095 :C7314AD1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:05:27.657092 :C7314AD4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:05:27.647 2025-08-31 18:05:27.657095 :C7314AD5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22608 pso_num=28 pso_serial#=145 2025-08-31 18:05:27.657256 :C7314AD6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22608 (legacy spawn) 2025-08-31 18:05:27.664740 :C7314AD7: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-08-31 18:05:27.664927 :C7314AE0: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-08-31 18:05:27.665868 :C7314AE1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:05:30.784355 :C7314BC6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:05:30.771 2025-08-31 18:05:30.784356 :C7314BC7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21076 pso_num=28 pso_serial#=146 2025-08-31 18:05:30.784495 :C7314BC8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21076 (legacy spawn) 2025-08-31 18:05:30.791036 :C7314BC9: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-08-31 18:05:30.791217 :C7314BCE: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-08-31 18:05:30.791999 :C7314BD3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:05:30.800782 :C7314BD6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:05:30.787 2025-08-31 18:05:30.800784 :C7314BD7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23336 pso_num=28 pso_serial#=147 2025-08-31 18:05:30.800920 :C7314BD8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23336 (legacy spawn) 2025-08-31 18:05:30.807407 :C7314BD9: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-08-31 18:05:30.807582 :C7314BE2: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-08-31 18:05:30.808411 :C7314BE3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:05:38.251119 :C7314E4A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:05:38.240 2025-08-31 18:05:38.251122 :C7314E4B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23296 pso_num=28 pso_serial#=148 2025-08-31 18:05:38.251299 :C7314E4C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23296 (legacy spawn) 2025-08-31 18:05:38.258515 :C7314E4D: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-08-31 18:05:38.258728 :C7314E54: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-08-31 18:05:38.259625 :C7314E57:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:04.085527 :C7315838:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:04.085 2025-08-31 18:06:04.085529 :C7315839:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16524 pso_num=28 pso_serial#=149 2025-08-31 18:06:04.085689 :C731583A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16524 (legacy spawn) 2025-08-31 18:06:04.092869 :C731583B: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-08-31 18:06:04.093075 :C7315840: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-08-31 18:06:04.093992 :C7315845:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:04.103684 :C7315848:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:04.101 2025-08-31 18:06:04.103687 :C7315849:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21892 pso_num=28 pso_serial#=150 2025-08-31 18:06:04.103869 :C731584A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21892 (legacy spawn) 2025-08-31 18:06:04.111200 :C731584B: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-08-31 18:06:04.111414 :C7315852: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-08-31 18:06:04.112328 :C7315855:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:07.186557 :C7315CEE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:07.174 2025-08-31 18:06:07.186559 :C7315CEF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23384 pso_num=28 pso_serial#=151 2025-08-31 18:06:07.186692 :C7315CF0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23384 (legacy spawn) 2025-08-31 18:06:07.193221 :C7315CF1: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-08-31 18:06:07.193413 :C7315CF6: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-08-31 18:06:07.194186 :C7315CFB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:07.212427 :C7315D27:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:07.205 2025-08-31 18:06:07.212428 :C7315D28:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20836 pso_num=28 pso_serial#=152 2025-08-31 18:06:07.212572 :C7315D29:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20836 (legacy spawn) 2025-08-31 18:06:07.219147 :C7315D2A: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-08-31 18:06:07.219176 :C7315D2D: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-08-31 18:06:07.219315 :C7315D34: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-08-31 18:06:07.220159 :C7315D41:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:12.332813 :C7315EBF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:12.323 2025-08-31 18:06:12.332816 :C7315EC0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23116 pso_num=28 pso_serial#=153 2025-08-31 18:06:12.332956 :C7315EC1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23116 (legacy spawn) 2025-08-31 18:06:12.339665 :C7315EC2: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-08-31 18:06:12.339831 :C7315ECA: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-08-31 18:06:12.340694 :C7315ECC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:15.738417 :C7315FE5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:15.729 2025-08-31 18:06:15.738419 :C7315FE6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22436 pso_num=28 pso_serial#=154 2025-08-31 18:06:15.738569 :C7315FE7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22436 (legacy spawn) 2025-08-31 18:06:15.745522 :C7315FE8: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-08-31 18:06:15.745716 :C7315FED: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-08-31 18:06:15.746712 :C7315FF2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:40.305252 :C731677B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:40.299 2025-08-31 18:06:40.305254 :C731677C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21448 pso_num=28 pso_serial#=155 2025-08-31 18:06:40.305416 :C731677D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21448 (legacy spawn) 2025-08-31 18:06:40.312224 :C731677E: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-08-31 18:06:40.312405 :C7316783: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-08-31 18:06:40.313290 :C7316788:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:06:40.322716 :C731678B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:06:40.315 2025-08-31 18:06:40.322719 :C731678C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22576 pso_num=28 pso_serial#=156 2025-08-31 18:06:40.322862 :C731678D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22576 (legacy spawn) 2025-08-31 18:06:40.329899 :C731678E: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-08-31 18:06:40.330082 :C7316797: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-08-31 18:06:40.331056 :C7316798:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-31 18:07:01.248394 :C73172FD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-31 18:07:01.243 2025-08-31 18:07:01.248396 :C73172FE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23320 pso_num=28 pso_serial#=157 2025-08-31 18:07:01.248529 :C73172FF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23320 osp_idx=26 osp_ver=283000 osp_pg=0 (spawn #545298) 2025-08-31 18:07:01.248530 :C7317300:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 18:07:01.248530 :C7317301:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=161 time=1049272549 2025-08-31 18:07:01.248531 :C7317302:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 18:07:01.248531 :C7317303:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 18:07:01.248532 :C7317304:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 18:07:01.248532 :C7317305:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 18:07:01.248532 :C7317306:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 18:07:01.249062 :C7317310: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: 23320, J002)