Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816140746\orcl_j001_22464_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:35460M/63366M, Ph+PgF:40804M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 22464, image: ORACLE.EXE (J001) *** 2025-08-16 14:07:46.719 *** SESSION ID:(225.6631) 2025-08-16 14:07:46.719 *** 2025-08-16 14:07:46.719 Process diagnostic dump for ORACLE.EXE (J001), OS id=22464, pid: 28, proc_ser: 163, sid: 225, sess_ser: 6631 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22464 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.239976 sec, exc=0.239976 sec, total=0.239976 sec wait times: max=0.500000 sec, heur=6.252060 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510090 sec, exc=0.510090 sec, total=0.510090 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=8 seq_num=9 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510097 sec, exc=0.510097 sec, total=0.510097 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.505097 sec, exc=0.505097 sec, total=0.505097 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000011 sec of elapsed time Sampled Session History of session 225 serial 6631 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 14:07:40 - 14:07:46] idle wait at each sample [session created at: 14:07:40] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 14:07:46.719 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 22464, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 14:03:57.790185 :C084C9BC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22276 (legacy spawn) 2025-08-16 14:03:57.810878 :C084C9BD: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-16 14:03:57.811030 :C084C9C6: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-16 14:03:57.811845 :C084C9C7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:04:34.003915 :C084D619:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:04:33.991 2025-08-16 14:04:34.003917 :C084D61A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22408 pso_num=28 pso_serial#=147 2025-08-16 14:04:34.004061 :C084D61B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22408 (legacy spawn) 2025-08-16 14:04:34.010535 :C084D61C: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-16 14:04:34.010704 :C084D621: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-16 14:04:34.011511 :C084D626:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:04:34.020268 :C084D629:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:04:34.006 2025-08-16 14:04:34.020271 :C084D62A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21528 pso_num=28 pso_serial#=148 2025-08-16 14:04:34.020408 :C084D62B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21528 (legacy spawn) 2025-08-16 14:04:34.026938 :C084D62C: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-16 14:04:34.027105 :C084D635: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-16 14:04:34.027902 :C084D636:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:04:40.093805 :C084D81D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:04:40.081 2025-08-16 14:04:40.093807 :C084D81E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21920 pso_num=28 pso_serial#=149 2025-08-16 14:04:40.093946 :C084D81F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21920 (legacy spawn) 2025-08-16 14:04:40.100578 :C084D820: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-16 14:04:40.100745 :C084D825: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-16 14:04:40.101617 :C084D82A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:05:39.577205 :C084F37B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:05:39.564 2025-08-16 14:05:39.577207 :C084F37C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18824 pso_num=28 pso_serial#=150 2025-08-16 14:05:39.577361 :C084F37D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18824 (legacy spawn) 2025-08-16 14:05:39.583827 :C084F37E: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-16 14:05:39.583992 :C084F384: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-16 14:05:39.584813 :C084F388:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:05:42.278598 :C084F45A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:05:42.270 2025-08-16 14:05:42.278600 :C084F45B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19324 pso_num=28 pso_serial#=151 2025-08-16 14:05:42.278740 :C084F45C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19324 (legacy spawn) 2025-08-16 14:05:42.285297 :C084F45D: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-16 14:05:42.285471 :C084F466: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-16 14:05:42.286338 :C084F467:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:05:43.395422 :C084F4EB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:05:43.395 2025-08-16 14:05:43.395423 :C084F4EC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22204 pso_num=28 pso_serial#=152 2025-08-16 14:05:43.395589 :C084F4ED:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22204 (legacy spawn) 2025-08-16 14:05:43.402762 :C084F4EE: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-16 14:05:43.403006 :C084F4F3: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-16 14:05:43.403988 :C084F4F8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:05:43.419846 :C084F4FB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:05:43.410 2025-08-16 14:05:43.419848 :C084F4FC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20804 pso_num=28 pso_serial#=153 2025-08-16 14:05:43.420015 :C084F4FD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20804 (legacy spawn) 2025-08-16 14:05:43.427056 :C084F4FE: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-16 14:05:43.427235 :C084F507: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-16 14:05:43.428161 :C084F508:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:05:46.544182 :C084F5FA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:05:46.531 2025-08-16 14:05:46.544184 :C084F5FB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21892 pso_num=28 pso_serial#=154 2025-08-16 14:05:46.544320 :C084F5FC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21892 (legacy spawn) 2025-08-16 14:05:46.550829 :C084F5FD: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-16 14:05:46.551004 :C084F602: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-16 14:05:46.551793 :C084F607:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:05:46.560467 :C084F60A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:05:46.546 2025-08-16 14:05:46.560469 :C084F60B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19496 pso_num=28 pso_serial#=155 2025-08-16 14:05:46.560606 :C084F60C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19496 (legacy spawn) 2025-08-16 14:05:46.567102 :C084F60D: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-16 14:05:46.567262 :C084F616: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-16 14:05:46.568077 :C084F617:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:06:44.511468 :C085092A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:06:44.498 2025-08-16 14:06:44.511470 :C085092B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17352 pso_num=28 pso_serial#=156 2025-08-16 14:06:44.511620 :C085092C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17352 (legacy spawn) 2025-08-16 14:06:44.518916 :C0850950: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-16 14:06:44.519097 :C0850959: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-16 14:06:44.519978 :C085095A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:06:47.795493 :C0850A53:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:06:47.787 2025-08-16 14:06:47.795495 :C0850A54:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21932 pso_num=28 pso_serial#=157 2025-08-16 14:06:47.795638 :C0850A55:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21932 (legacy spawn) 2025-08-16 14:06:47.802111 :C0850A56: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-16 14:06:47.802334 :C0850A5F: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-16 14:06:47.803132 :C0850A60:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:06:55.997452 :C0850CF6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:06:55.997 2025-08-16 14:06:55.997454 :C0850CF7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22468 pso_num=28 pso_serial#=158 2025-08-16 14:06:55.997600 :C0850CF8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22468 (legacy spawn) 2025-08-16 14:06:56.004386 :C0850CF9: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-16 14:06:56.004579 :C0850CFE: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-16 14:06:56.005599 :C0850D03:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:06:56.014873 :C0850D06:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:06:56.013 2025-08-16 14:06:56.014874 :C0850D07:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21780 pso_num=28 pso_serial#=159 2025-08-16 14:06:56.015016 :C0850D08:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21780 (legacy spawn) 2025-08-16 14:06:56.021919 :C0850D09: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-16 14:06:56.022095 :C0850D12: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-16 14:06:56.023105 :C0850D13:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:06:59.296421 :C0850E0B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:06:59.283 2025-08-16 14:06:59.296423 :C0850E0C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21868 pso_num=28 pso_serial#=160 2025-08-16 14:06:59.296563 :C0850E0D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21868 (legacy spawn) 2025-08-16 14:06:59.303091 :C0850E0E: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-16 14:06:59.303257 :C0850E13: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-16 14:06:59.304031 :C0850E18:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:06:59.312804 :C0850E1B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:06:59.299 2025-08-16 14:06:59.312806 :C0850E1C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17836 pso_num=28 pso_serial#=161 2025-08-16 14:06:59.312950 :C0850E1D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17836 (legacy spawn) 2025-08-16 14:06:59.319478 :C0850E1E: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-16 14:06:59.319643 :C0850E27: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-16 14:06:59.320444 :C0850E28:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:07:01.833585 :C0850F01:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:07:01.824 2025-08-16 14:07:01.833587 :C0850F02:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19264 pso_num=28 pso_serial#=162 2025-08-16 14:07:01.833715 :C0850F03:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19264 (legacy spawn) 2025-08-16 14:07:01.841971 :C0850F04: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-16 14:07:01.842151 :C0850F09: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-16 14:07:01.843015 :C0850F0E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 14:07:32.435647 :C0851C1E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 14:07:32.424 2025-08-16 14:07:32.435650 :C0851C1F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22464 pso_num=28 pso_serial#=163 2025-08-16 14:07:32.435767 :C0851C20:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22464 osp_idx=26 osp_ver=232516 osp_pg=0 (spawn #494722) 2025-08-16 14:07:32.435768 :C0851C21: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-16 14:07:32.435769 :C0851C22:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=78 time=4033921736 2025-08-16 14:07:32.435769 :C0851C23:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 14:07:32.435769 :C0851C24:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 14:07:32.435770 :C0851C25:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 14:07:32.435770 :C0851C26:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 14:07:32.435771 :C0851C27:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 14:07:32.436247 :C0851C28: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 2025-08-16 14:07:38.728777 :C0851FAE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 14:07:38.832797 :C0852055:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 14:07:38.832797*:C0852064:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FC5C718 estart=1755324458 eid=16786193 ctx=0x000000077FD6A000 cwh=0x000000000FC50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-16 14:07:38.832797*:C0852065:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FC5C718 curCtx=0x000000000FC58938 pubTabIdxCnt=0 2025-08-16 14:07:38.832797*:C0852066:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FC5C718 2025-08-16 14:07:38.832797*:C0852067:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FC5C718 2025-08-16 14:07:38.832797*:C085206A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FC5C718 curCtx=0x000000000FC58938 xsc->flg4=65792 flags=2 2025-08-16 14:07:38.832797*:C085206B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FC5C718 2025-08-16 14:07:38.838037 :C0852074: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-16 14:07:38.838077 :C0852077:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-08-16 14:07:38.842147 :C0852087: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-16 14:07:38.949202 :C085208F: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-16 14:07:39.077766 :C0852097: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-16 14:07:39.204828 :C08520AB: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-16 14:07:40.324317 :C08520E7: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-16 14:07:40.325277 :C08520EF: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-16 14:07:40.326920 :C08520F7: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-16 14:07:40.329557 :C08520FF: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-16 14:07:40.332683 :C0852107: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-16 14:07:40.347979 :C0852130: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-16 14:07:40.348796 :C0852138: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-16 14:07:40.349394 :C0852140: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-16 14:07:40.349936 :C0852148: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-16 14:07:40.352598 :C0852150: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-16 14:07:40.353519 :C0852158:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.354273 :C0852159:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.355044 :C085215A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.355445 :C085215B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.355451 :C085215C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.356024 :C085215D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.356585 :C085215E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.356902 :C085215F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.357971 :C0852160:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.365410*:C085216B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B482580 estart=1755324459 eid=16778755 ctx=0x000000077B458BE8 cwh=0x000000000FCABC10 cpu=0 buffg=350 interb=98304 r=12 rb=98304 w=0 wb=0 2025-08-16 14:07:40.365410*:C085216C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B482580 curCtx=0x000000002B539C90 pubTabIdxCnt=0 2025-08-16 14:07:40.365410*:C085216D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B482580 2025-08-16 14:07:40.365410*:C085216E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B482580 2025-08-16 14:07:40.381031*:C085216F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B482580 curCtx=0x000000002B539C90 xsc->flg4=65792 flags=2 2025-08-16 14:07:40.381031*:C0852170:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B482580 2025-08-16 14:07:40.393213 :C0852171: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-16 14:07:40.393269 :C0852174:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.394139 :C085217A: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-16 14:07:40.395007 :C0852182:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.395417 :C0852183: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-16 14:07:40.416101 :C085218B: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-16 14:07:40.416151 :C085218E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.416963 :C0852194: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-16 14:07:40.417807 :C085219C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.418303 :C085219D: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-16 14:07:40.440271 :C08521A7: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-16 14:07:40.440324 :C08521AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.441184 :C08521B0: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-16 14:07:40.442152 :C08521C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-16 14:07:40.442684 :C08521C3: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-16 14:07:40.463725 :C08521D3: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-16 14:07:40.463771 :C08521D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-16 14:07:40.464564 :C08521DE: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-16 14:07:40.464982 :C08521E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-16 14:07:40.465330 :C08521FD: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-16 14:07:40.465507 :C0852205: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-16 14:07:40.465650 :C085220D: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-16 14:07:40.465794 :C0852215:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-08-16 14:07:40.469821 :C085222E: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-16 14:07:40.469895 :C0852231:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-16 14:07:40.469954 :C085223E: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 22464, J001)