Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802100521\orcl_j001_20036_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:35828M/63366M, Ph+PgF:41490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 20036, image: ORACLE.EXE (J001) *** 2025-08-02 10:05:21.285 *** SESSION ID:(201.37179) 2025-08-02 10:05:21.285 *** 2025-08-02 10:05:21.285 Process diagnostic dump for ORACLE.EXE (J001), OS id=20036, pid: 25, proc_ser: 48, sid: 201, sess_ser: 37179 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20036 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.454633 sec, exc=0.454633 sec, total=0.454633 sec wait times: max=0.500000 sec, heur=2.440083 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.485420 sec, exc=0.485420 sec, total=0.485420 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 201 serial 37179 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 10:05:18 - 10:05:21] idle wait at each sample [session created at: 10:05:18] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 10:05:21.285 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 20036, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 09:54:54.306795 :BA46FFA1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 09:54:54.306795 :BA46FFA2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 09:54:54.307285 :BA46FFA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 09:55:24.355320 :BA47099B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 09:55:54.373481 :BA47133F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 09:55:54.362 2025-08-02 09:55:54.373483 :BA471340:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8552 pso_num=25 pso_serial#=38 2025-08-02 09:55:54.373605 :BA471341:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8552 osp_idx=23 osp_ver=224550 osp_pg=0 (spawn #447469) 2025-08-02 09:55:54.373606 :BA471342:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 09:55:54.373607 :BA471343:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=2809223673 2025-08-02 09:55:54.373607 :BA471344:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 09:55:54.373607 :BA471345:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 09:55:54.373608 :BA471346:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 09:55:54.373608 :BA471347:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 09:55:54.373608 :BA471348:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 09:55:54.374074 :BA471349:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 09:56:24.440940 :BA471D5B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 09:56:54.517609 :BA472716:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 09:56:54.506 2025-08-02 09:56:54.517611 :BA472717:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16580 pso_num=25 pso_serial#=39 2025-08-02 09:56:54.517744 :BA472718:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16580 osp_idx=23 osp_ver=224551 osp_pg=0 (spawn #447471) 2025-08-02 09:56:54.517744 :BA472719:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 09:56:54.517745 :BA47271A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=2809283814 2025-08-02 09:56:54.517745 :BA47271B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 09:56:54.517746 :BA47271C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 09:56:54.517746 :BA47271D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 09:56:54.517746 :BA47271E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 09:56:54.517747 :BA47271F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 09:56:54.518266 :BA472720:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 09:57:24.591548 :BA4730E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 09:57:54.604308 :BA473AB6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 09:57:54.593 2025-08-02 09:57:54.604310 :BA473AB7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19500 pso_num=25 pso_serial#=40 2025-08-02 09:57:54.604443 :BA473AB8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19500 osp_idx=23 osp_ver=224552 osp_pg=0 (spawn #447473) 2025-08-02 09:57:54.604443 :BA473AB9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 09:57:54.604443 :BA473ABA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=2809343908 2025-08-02 09:57:54.604444 :BA473ABB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 09:57:54.604444 :BA473ABC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 09:57:54.604445 :BA473ABD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 09:57:54.604445 :BA473ABE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 09:57:54.604445 :BA473ABF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 09:57:54.604944 :BA473AC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 09:58:24.678640 :BA474556:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 09:58:54.665100 :BA474F2D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 09:58:54.654 2025-08-02 09:58:54.665102 :BA474F2E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19156 pso_num=25 pso_serial#=41 2025-08-02 09:58:54.665222 :BA474F2F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19156 osp_idx=23 osp_ver=224553 osp_pg=0 (spawn #447475) 2025-08-02 09:58:54.665223 :BA474F30:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 09:58:54.665223 :BA474F31:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=2809403970 2025-08-02 09:58:54.665223 :BA474F32:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 09:58:54.665224 :BA474F33:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 09:58:54.665224 :BA474F34:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 09:58:54.665225 :BA474F35:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 09:58:54.665225 :BA474F36:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 09:58:54.665713 :BA474F37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 09:59:24.713762 :BA475914:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 09:59:54.746088 :BA4762E4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 09:59:54.735 2025-08-02 09:59:54.746090 :BA4762E5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19016 pso_num=25 pso_serial#=42 2025-08-02 09:59:54.746224 :BA4762E6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19016 osp_idx=23 osp_ver=224554 osp_pg=0 (spawn #447477) 2025-08-02 09:59:54.746225 :BA4762E7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 09:59:54.746225 :BA4762E8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=2809464048 2025-08-02 09:59:54.746226 :BA4762E9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 09:59:54.746226 :BA4762EA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 09:59:54.746226 :BA4762EB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 09:59:54.746226 :BA4762EC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 09:59:54.746227 :BA4762ED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 09:59:54.746799 :BA4762EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:00:24.846716 :BA476CD1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 10:00:53.927127 :BA477721:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 10:00:53.912 2025-08-02 10:00:53.927129 :BA477722:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14152 pso_num=25 pso_serial#=43 2025-08-02 10:00:53.927254 :BA477723:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14152 osp_idx=23 osp_ver=224555 osp_pg=0 (spawn #447482) 2025-08-02 10:00:53.927255 :BA477724:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 10:00:53.927255 :BA477725:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=2809523220 2025-08-02 10:00:53.927256 :BA477726:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 10:00:53.927256 :BA477727:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 10:00:53.927256 :BA477728:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 10:00:53.927257 :BA477729:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 10:00:53.927257 :BA47772A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 10:00:53.927740 :BA47772B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.927785 :BA477731:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-02 10:00:53.935648 :BA47773F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.936670 :BA477747:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.937255 :BA47774F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.937704 :BA477757:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.938140 :BA47775F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.940596 :BA477767:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.941735 :BA47776F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.992806 :BA477777:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.993710 :BA47777F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.993957 :BA477787:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.994171 :BA47778F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:00:53.995956 :BA477797:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 10:00:54.877543 :BA477810:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 10:00:54.866 2025-08-02 10:00:54.877545 :BA477811:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10156 pso_num=25 pso_serial#=44 2025-08-02 10:00:54.877674 :BA477812:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10156 osp_idx=23 osp_ver=224556 osp_pg=0 (spawn #447484) 2025-08-02 10:00:54.877674 :BA477813:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 10:00:54.877675 :BA477814:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=2809524173 2025-08-02 10:00:54.877675 :BA477815:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 10:00:54.877675 :BA477816:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 10:00:54.877676 :BA477817:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 10:00:54.877676 :BA477818:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 10:00:54.877677 :BA477819:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 10:00:54.878189 :BA47781A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:01:24.946530 :BA478421:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 10:01:54.935342 :BA478DFE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 10:01:54.924 2025-08-02 10:01:54.935344 :BA478DFF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19624 pso_num=25 pso_serial#=45 2025-08-02 10:01:54.935477 :BA478E00:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19624 osp_idx=23 osp_ver=224557 osp_pg=0 (spawn #447486) 2025-08-02 10:01:54.935478 :BA478E01:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 10:01:54.935478 :BA478E02:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=2809584236 2025-08-02 10:01:54.935479 :BA478E03:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 10:01:54.935479 :BA478E04:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 10:01:54.935479 :BA478E05:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 10:01:54.935480 :BA478E06:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 10:01:54.935480 :BA478E07:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 10:01:54.935963 :BA478E08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:02:24.999727 :BA4797C6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 10:02:55.005953 :BA47A1A6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 10:02:54.994 2025-08-02 10:02:55.005955 :BA47A1A7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17392 pso_num=25 pso_serial#=46 2025-08-02 10:02:55.006125 :BA47A1A8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17392 osp_idx=23 osp_ver=224558 osp_pg=0 (spawn #447488) 2025-08-02 10:02:55.006126 :BA47A1A9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 10:02:55.006126 :BA47A1AA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=2809644298 2025-08-02 10:02:55.006127 :BA47A1AB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 10:02:55.006127 :BA47A1AC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 10:02:55.006128 :BA47A1AD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 10:02:55.006128 :BA47A1AE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 10:02:55.006129 :BA47A1AF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 10:02:55.006651 :BA47A1B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:03:10.016813 :BA47A76B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-02 10:03:40.112506 :BA47B12A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 10:03:55.066141 :BA47B623:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 10:03:55.054 2025-08-02 10:03:55.066143 :BA47B624:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18928 pso_num=25 pso_serial#=47 2025-08-02 10:03:55.066257 :BA47B625:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18928 osp_idx=23 osp_ver=224559 osp_pg=0 (spawn #447490) 2025-08-02 10:03:55.066258 :BA47B626:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 10:03:55.066258 :BA47B627:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=2809704361 2025-08-02 10:03:55.066258 :BA47B628:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 10:03:55.066259 :BA47B629:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 10:03:55.066259 :BA47B62A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 10:03:55.066259 :BA47B62B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 10:03:55.066260 :BA47B62C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 10:03:55.066743 :BA47B62D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:04:25.140656 :BA47C010:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-02 10:04:55.135875 :BA47CA00:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-02 10:04:55.125 2025-08-02 10:04:55.135877 :BA47CA01:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20036 pso_num=25 pso_serial#=48 2025-08-02 10:04:55.136008 :BA47CA02:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20036 osp_idx=23 osp_ver=224560 osp_pg=0 (spawn #447492) 2025-08-02 10:04:55.136009 :BA47CA03:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 10:04:55.136010 :BA47CA04:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=2809764439 2025-08-02 10:04:55.136010 :BA47CA05:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 10:04:55.136010 :BA47CA06:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 10:04:55.136011 :BA47CA07:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 10:04:55.136011 :BA47CA08:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 10:04:55.136011 :BA47CA09:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 10:04:55.136499 :BA47CA0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 10:05:17.112657 :BA47D281:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-02 10:05:17.217905 :BA47D5CA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-02 10:05:17.217905*:BA47D5D8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1754100316 eid=16785321 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-02 10:05:17.217905*:BA47D5D9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-08-02 10:05:17.217905*:BA47D5DA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-08-02 10:05:17.217905*:BA47D5DB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-08-02 10:05:17.217905*:BA47D5DC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-08-02 10:05:17.217905*:BA47D5DD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-08-02 10:05:17.223338 :BA47D5E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:17.223380 :BA47D5EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-02 10:05:17.228377 :BA47D5FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:17.355877 :BA47D603:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:17.494626 :BA47D627:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:17.633574 :BA47D62F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.766382 :BA47D68E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.767350 :BA47D696:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.784992 :BA47D69E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.785610 :BA47D6A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.786197 :BA47D6AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.786693 :BA47D6B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.789295 :BA47D6BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.790205 :BA47D6C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-02 10:05:18.790945 :BA47D6C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-02 10:05:18.791704 :BA47D6C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-02 10:05:18.792091 :BA47D6C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-02 10:05:18.792097 :BA47D6CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-02 10:05:18.792643 :BA47D6CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-02 10:05:18.793204 :BA47D6CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-02 10:05:18.793486 :BA47D6CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-02 10:05:18.794484 :BA47D6CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-02 10:05:18.841828 :BA47D6D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.841881 :BA47D6DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-02 10:05:18.842792 :BA47D6E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.843256 :BA47D6EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.843562 :BA47D6F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.843740 :BA47D6FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.843884 :BA47D702:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.844039 :BA47D70A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-02 10:05:18.848126 :BA47D70B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.848204 :BA47D70E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-02 10:05:18.848294 :BA47D71B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 20036, J001)