Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250710220004\orcl_j004_17104_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:36451M/63366M, Ph+PgF:42544M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 17104, image: ORACLE.EXE (J004) *** 2025-07-10 22:00:04.730 *** SESSION ID:(241.3019) 2025-07-10 22:00:04.730 *** 2025-07-10 22:00:04.730 Process diagnostic dump for ORACLE.EXE (J004), OS id=17104, pid: 30, proc_ser: 148, sid: 241, sess_ser: 3019 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17104 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.434601 sec, exc=0.434601 sec, total=0.434601 sec wait times: max=0.500000 sec, heur=1.428891 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.494276 sec, exc=0.494276 sec, total=0.494276 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 241 serial 3019 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:03 - 22:00:04] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-10 22:00:04.730 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 17104, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-08 23:59:05.897638 :AF6BE591:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 23:59:39.022746 :AF6BF1B8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-08 23:59:41.250130 :AF6BF2D4:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-08 23:59:41.238 2025-07-08 23:59:41.250132 :AF6BF2D5:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17636 pso_num=30 pso_serial#=137 2025-07-08 23:59:41.250246 :AF6BF2D6:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=17636 osp_idx=28 osp_ver=1411 osp_pg=0 (spawn #366112) 2025-07-08 23:59:41.250246 :AF6BF2D7:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 23:59:41.250247 :AF6BF2D8:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=116 time=699957142 2025-07-08 23:59:41.250247 :AF6BF2D9:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 23:59:41.250247 :AF6BF2DA:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 23:59:41.250248 :AF6BF2DB:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 23:59:41.250248 :AF6BF2DC:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 23:59:41.250249 :AF6BF2DD:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 23:59:41.250757 :AF6BF2DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 00:00:06.268853 :AF6BFC15:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-09 00:00:36.342055 :AF6C0756:db_trace:ksl2.c@2324:kslwtbctx(): [10005:30:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-09 00:00:36.342062 :AF6C0757:db_trace:ksl2.c@2611:kslwtectx(): [10005:30:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-09 00:00:36.342063 :AF6C0758:db_trace:ksl2.c@2619:kslwtectx(): [10005:30:0] KSL WAIT END wait times (usecs) - snap=8, exc=8, tot=8 2025-07-09 00:00:36.342142 :AF6C0759:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 01:00:13.963030 :AF70CA53:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 01:00:13.960 2025-07-09 01:00:13.963032 :AF70CA54:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13728 pso_num=30 pso_serial#=138 2025-07-09 01:00:13.963171 :AF70CA55:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=13728 (legacy spawn) 2025-07-09 01:00:13.980673 :AF70CA63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:13.980835 :AF70CA6C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-09 01:00:13.981791 :AF70CA6D:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 01:00:24.465343 :AF70CE60:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 01:00:24.453 2025-07-09 01:00:24.465345 :AF70CE61:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16492 pso_num=30 pso_serial#=139 2025-07-09 01:00:24.465471 :AF70CE62:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=16492 osp_idx=28 osp_ver=1413 osp_pg=0 (spawn #366251) 2025-07-09 01:00:24.465472 :AF70CE63:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 01:00:24.465472 :AF70CE64:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=117 time=703600345 2025-07-09 01:00:24.465473 :AF70CE65:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 01:00:24.465473 :AF70CE66:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 01:00:24.465473 :AF70CE67:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 01:00:24.465474 :AF70CE68:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 01:00:24.465474 :AF70CE69:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 01:00:24.466001 :AF70CE6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.466048 :AF70CE70:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-09 01:00:24.473459 :AF70CE7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.474511 :AF70CE86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.475013 :AF70CE8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.475533 :AF70CE96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.475972 :AF70CE9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.478410 :AF70CEA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.479581 :AF70CEAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.529791 :AF70CEB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.530733 :AF70CEBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.530993 :AF70CEC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.531211 :AF70CECE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 01:00:24.532917 :AF70CED6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 22:00:00.182059 :AFD50517:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 22:00:00.170 2025-07-09 22:00:00.182059 :AFD50518:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15576 pso_num=30 pso_serial#=140 2025-07-09 22:00:00.182210 :AFD50519:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=15576 osp_idx=28 osp_ver=1414 osp_pg=0 (spawn #369153) 2025-07-09 22:00:00.182210 :AFD5051A:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 22:00:00.182211 :AFD5051B:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=118 time=779176064 2025-07-09 22:00:00.182211 :AFD5051C:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 22:00:00.182212 :AFD5051D:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 22:00:00.182213 :AFD5051E:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 22:00:00.182213 :AFD5051F:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 22:00:00.182214 :AFD50520:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 22:00:00.182811 :AFD50521:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 22:00:30.241579 :AFD516B8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 23:59:05.715508 :AFDE81AD:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 23:59:05.712 2025-07-09 23:59:05.715510 :AFDE81AE:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16340 pso_num=30 pso_serial#=141 2025-07-09 23:59:05.715642 :AFDE81AF:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16340 (legacy spawn) 2025-07-09 23:59:05.729609 :AFDE81B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:09.018444 :AFDE82F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:09.018627 :AFDE8300:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-09 23:59:09.019891 :AFDE8301:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 23:59:09.276550 :AFDE8304:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 23:59:09.273 2025-07-09 23:59:09.276552 :AFDE8305:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16544 pso_num=30 pso_serial#=142 2025-07-09 23:59:09.276685 :AFDE8306:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16544 (legacy spawn) 2025-07-09 23:59:09.290717 :AFDE8307:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:42.428659 :AFDE8F11:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 23:59:42.569807 :AFDE8F3C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 23:59:42.567 2025-07-09 23:59:42.569809 :AFDE8F3D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15528 pso_num=30 pso_serial#=143 2025-07-09 23:59:42.569944 :AFDE8F3E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=15528 (legacy spawn) 2025-07-09 23:59:42.584100 :AFDE8F3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:45.780557 :AFDE902B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:45.780745 :AFDE9030:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-09 23:59:45.781925 :AFDE9035:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-09 23:59:46.036652 :AFDE9044:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-09 23:59:46.023 2025-07-09 23:59:46.036654 :AFDE9045:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13284 pso_num=30 pso_serial#=144 2025-07-09 23:59:46.036808 :AFDE9046:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=13284 (legacy spawn) 2025-07-09 23:59:46.051983 :AFDE9047:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 00:00:19.243142 :AFDE9C0B:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 01:00:15.240087 :AFE35E62:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 01:00:15.231 2025-07-10 01:00:15.240088 :AFE35E63:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10808 pso_num=30 pso_serial#=145 2025-07-10 01:00:15.240221 :AFE35E64:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=10808 (legacy spawn) 2025-07-10 01:00:15.246697 :AFE35E65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 01:00:15.246872 :AFE35E6E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-10 01:00:15.247704 :AFE35E6F:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 01:00:15.864782 :AFE35ED5:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 01:00:15.856 2025-07-10 01:00:15.864785 :AFE35ED6:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17512 pso_num=30 pso_serial#=146 2025-07-10 01:00:15.864922 :AFE35ED7:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17512 (legacy spawn) 2025-07-10 01:00:15.871476 :AFE35ED8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 01:00:15.871652 :AFE35EDD:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-10 01:00:15.872506 :AFE35EE2:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 01:00:25.054832 :AFE361DF:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 01:00:25.042 2025-07-10 01:00:25.054834 :AFE361E0:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15076 pso_num=30 pso_serial#=147 2025-07-10 01:00:25.054972 :AFE361E1:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=15076 (legacy spawn) 2025-07-10 01:00:25.061418 :AFE361E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 01:00:25.061580 :AFE361E7:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-07-10 01:00:25.062378 :AFE361EC:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 22:00:00.870413 :B045C590:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 22:00:00.870 2025-07-10 22:00:00.870414 :B045C591:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17104 pso_num=30 pso_serial#=148 2025-07-10 22:00:00.870571 :B045C592:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=17104 osp_idx=28 osp_ver=1422 osp_pg=0 (spawn #372487) 2025-07-10 22:00:00.870572 :B045C593:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 22:00:00.870573 :B045C594:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=119 time=865576751 2025-07-10 22:00:00.870574 :B045C595:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 22:00:00.870574 :B045C596:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 22:00:00.870575 :B045C597:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 22:00:00.870575 :B045C598:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 22:00:00.870576 :B045C599:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 22:00:00.871129 :B045C59A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.876132 :B045C5B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.876173 :B045C5BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-07-10 22:00:00.880444 :B045C71A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.261241 :B045C99B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.313881 :B045C9AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.329534 :B045C9B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.386892 :B045C9BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.519816 :B045C9CB:db_trace:ktu.c@4871:ktuaex1r(): [10442:30:241] Extension usn 5, ext 0, nex 2 2025-07-10 22:00:01.519823 :B045C9CC:db_trace:ktusm.c@2117:ktusmasp(): [10445:30:241] des1:tsn:1 rdba:0x0081a1e1 where: kduwh01: kdusru 2025-07-10 22:00:01.519932 :B045C9CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.634762 :B045C9E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.644452 :B045C9E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.659949 :B045C9F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.675067 :B045C9F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.690920 :B045CA01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.700414 :B045CA09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.710202 :B045CA11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.725380 :B045CA19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.740737 :B045CA21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.750399 :B045CA29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.760376 :B045CA31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.775835 :B045CA39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.785347 :B045CA41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.801259 :B045CA49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.816919 :B045CA51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.832988 :B045CA59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.848365 :B045CA61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.858066 :B045CA69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.873605 :B045CA71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.882557 :B045CA79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.892118 :B045CA81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.901608 :B045CA89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.917318 :B045CA91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.926869 :B045CA99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.942530 :B045CAA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.950011 :B045CAA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.966084 :B045CAB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.981748 :B045CAB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:01.991986 :B045CAC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.002317 :B045CAC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.012738 :B045CAD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.028202 :B045CAD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.043756 :B045CAE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.055978 :B045CAE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.071150 :B045CAF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.081167 :B045CAF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.090660 :B045CB01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.099943 :B045CB09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.115298 :B045CB11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.125012 :B045CB19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.132249 :B045CB21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.139657 :B045CB29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.146883 :B045CB31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.154181 :B045CB39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.161772 :B045CB41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.169249 :B045CB49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.176714 :B045CB51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.541057*:B045CB85:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000034476918 estart=1752156001 eid=16777433 ctx=0x00000007831EA738 cwh=0x00000000344A0248 cpu=359375 buffg=55410 interb=2433024 r=15 rb=901120 w=8 wb=1531904 2025-07-10 22:00:02.541057*:B045CB86:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000034476918 curCtx=0x000000004DE81600 pubTabIdxCnt=0 2025-07-10 22:00:02.541057*:B045CB87:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000034476918 2025-07-10 22:00:02.541057*:B045CB88:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000034476918 2025-07-10 22:00:02.541057*:B045CB89:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003196C288 estart=1752156000 eid=16777324 ctx=0x000000079F654AA8 cwh=0x0000000031960158 cpu=1640625 buffg=142516 interb=29384704 r=1383 rb=25559040 w=20 wb=3825664 2025-07-10 22:00:02.541057*:B045CB8A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003196C288 curCtx=0x000000004DBDDEC8 pubTabIdxCnt=0 2025-07-10 22:00:02.541057*:B045CB8B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003196C288 2025-07-10 22:00:02.541057*:B045CB8C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003196C288 2025-07-10 22:00:02.714026*:B045CCC1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000034476918 curCtx=0x000000004DE81600 xsc->flg4=65792 flags=1 2025-07-10 22:00:02.714026*:B045CCC2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000034476918 2025-07-10 22:00:03.301319 :B045CD74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.301648 :B045CD7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.301648*:B045CD84:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003196C288 curCtx=0x000000004DBDDEC8 xsc->flg4=65792 flags=2 2025-07-10 22:00:03.301648*:B045CD85:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003196C288 2025-07-10 22:00:03.301946 :B045CD86:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-07-10 22:00:03.306290 :B045CD87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.306365 :B045CD8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.306449 :B045CD97:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 17104, J004)