Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250828220005\orcl_j000_19592_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:35147M/63366M, Ph+PgF:40176M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 19592, image: ORACLE.EXE (J000) *** 2025-08-28 22:00:06.046 *** SESSION ID:(193.42209) 2025-08-28 22:00:06.046 *** 2025-08-28 22:00:06.046 Process diagnostic dump for ORACLE.EXE (J000), OS id=19592, pid: 24, proc_ser: 182, sid: 193, sess_ser: 42209 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19592 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) 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.267429 sec, exc=0.267429 sec, total=0.267429 sec wait times: max=0.500000 sec, heur=2.009912 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=3 seq_num=4 snap_id=1 wait times: snap=0.510107 sec, exc=0.510107 sec, total=0.510107 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.498749 sec, exc=0.498749 sec, total=0.498749 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 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.235622 sec, exc=0.235622 sec, total=0.235622 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.497969 sec, exc=0.497969 sec, total=0.497969 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 193 serial 42209 --------------------------------------------------- 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, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-28 22:00:06.046 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 19592, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-28 21:52:19.127133 :C5EE8FA0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19060 pso_num=24 pso_serial#=173 2025-08-28 21:52:19.127262 :C5EE8FA1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19060 osp_idx=22 osp_ver=810828 osp_pg=0 (spawn #535795) 2025-08-28 21:52:19.127263 :C5EE8FA2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:52:19.127263 :C5EE8FA3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=209 time=803590424 2025-08-28 21:52:19.127263 :C5EE8FA4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:52:19.127264 :C5EE8FA5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:52:19.127264 :C5EE8FA6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:52:19.127264 :C5EE8FA7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:52:19.127265 :C5EE8FA8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:52:19.127739 :C5EE8FA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:52:49.197401 :C5EE997C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:53:19.192172 :C5EEA377:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:53:19.180 2025-08-28 21:53:19.192174 :C5EEA378:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23160 pso_num=24 pso_serial#=174 2025-08-28 21:53:19.192295 :C5EEA379:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23160 osp_idx=22 osp_ver=810829 osp_pg=0 (spawn #535797) 2025-08-28 21:53:19.192296 :C5EEA37A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:53:19.192296 :C5EEA37B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=210 time=803650487 2025-08-28 21:53:19.192296 :C5EEA37C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:53:19.192296 :C5EEA37D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:53:19.192297 :C5EEA37E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:53:19.192297 :C5EEA37F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:53:19.192297 :C5EEA380:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:53:19.192771 :C5EEA381:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:53:49.332359 :C5EEADE4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:54:19.296819 :C5EEB764:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:54:19.286 2025-08-28 21:54:19.296820 :C5EEB765:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21728 pso_num=24 pso_serial#=175 2025-08-28 21:54:19.296937 :C5EEB766:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21728 osp_idx=22 osp_ver=810830 osp_pg=0 (spawn #535800) 2025-08-28 21:54:19.296938 :C5EEB767:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:54:19.296939 :C5EEB768:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=211 time=803710596 2025-08-28 21:54:19.296939 :C5EEB769:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:54:19.296939 :C5EEB76A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:54:19.296940 :C5EEB76B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:54:19.296940 :C5EEB76C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:54:19.296940 :C5EEB76D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:54:19.297424 :C5EEB76E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:54:49.351526 :C5EEC115:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:55:19.383868 :C5EECA8A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:55:19.373 2025-08-28 21:55:19.383870 :C5EECA8B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23540 pso_num=24 pso_serial#=176 2025-08-28 21:55:19.384004 :C5EECA8C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23540 osp_idx=22 osp_ver=810831 osp_pg=0 (spawn #535802) 2025-08-28 21:55:19.384005 :C5EECA8D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:55:19.384006 :C5EECA8E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=212 time=803770690 2025-08-28 21:55:19.384006 :C5EECA8F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:55:19.384006 :C5EECA90:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:55:19.384007 :C5EECA91:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:55:19.384007 :C5EECA92:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:55:19.384007 :C5EECA93:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:55:19.384473 :C5EECA94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:55:49.465898 :C5EED41A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:56:19.455309 :C5EEDDC4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:56:19.443 2025-08-28 21:56:19.455310 :C5EEDDC5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22092 pso_num=24 pso_serial#=177 2025-08-28 21:56:19.455437 :C5EEDDC6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22092 osp_idx=22 osp_ver=810832 osp_pg=0 (spawn #535804) 2025-08-28 21:56:19.455438 :C5EEDDC7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:56:19.455438 :C5EEDDC8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=213 time=803830752 2025-08-28 21:56:19.455439 :C5EEDDC9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:56:19.455439 :C5EEDDCA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:56:19.455439 :C5EEDDCB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:56:19.455440 :C5EEDDCC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:56:19.455440 :C5EEDDCD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:56:19.455917 :C5EEDDCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:56:49.550044 :C5EEE7DC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:57:19.540478 :C5EEF19B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:57:19.529 2025-08-28 21:57:19.540480 :C5EEF19C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16724 pso_num=24 pso_serial#=178 2025-08-28 21:57:19.540607 :C5EEF19D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16724 osp_idx=22 osp_ver=810833 osp_pg=0 (spawn #535806) 2025-08-28 21:57:19.540608 :C5EEF19E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:57:19.540608 :C5EEF19F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=214 time=803890846 2025-08-28 21:57:19.540608 :C5EEF1A0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:57:19.540609 :C5EEF1A1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:57:19.540609 :C5EEF1A2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:57:19.540609 :C5EEF1A3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:57:19.540610 :C5EEF1A4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:57:19.541113 :C5EEF1A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:57:49.608466 :C5EEFB10:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:58:19.640461 :C5EF0531:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:58:19.629 2025-08-28 21:58:19.640463 :C5EF0532:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22344 pso_num=24 pso_serial#=179 2025-08-28 21:58:19.640594 :C5EF0533:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22344 osp_idx=22 osp_ver=810834 osp_pg=0 (spawn #535808) 2025-08-28 21:58:19.640595 :C5EF0534:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:58:19.640595 :C5EF0535:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=215 time=803950940 2025-08-28 21:58:19.640596 :C5EF0536:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:58:19.640596 :C5EF0537:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:58:19.640597 :C5EF0538:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:58:19.640597 :C5EF0539:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:58:19.640597 :C5EF053A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:58:19.641084 :C5EF053B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:58:49.731521 :C5EF1013:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 21:59:19.717976 :C5EF19D9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 21:59:19.706 2025-08-28 21:59:19.717978 :C5EF19DA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12612 pso_num=24 pso_serial#=180 2025-08-28 21:59:19.718100 :C5EF19DB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12612 osp_idx=22 osp_ver=810835 osp_pg=0 (spawn #535811) 2025-08-28 21:59:19.718100 :C5EF19DC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 21:59:19.718101 :C5EF19DD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=216 time=804011018 2025-08-28 21:59:19.718101 :C5EF19DE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 21:59:19.718101 :C5EF19DF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 21:59:19.718102 :C5EF19E0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 21:59:19.718102 :C5EF19E1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 21:59:19.718103 :C5EF19E2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 21:59:19.718594 :C5EF19E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 21:59:49.812914 :C5EF2396:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 22:00:00.035345 :C5EF2701:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 22:00:00.026 2025-08-28 22:00:00.035348 :C5EF2702:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22612 pso_num=24 pso_serial#=181 2025-08-28 22:00:00.035484 :C5EF2703:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22612 osp_idx=22 osp_ver=810836 osp_pg=0 (spawn #535813) 2025-08-28 22:00:00.035485 :C5EF2704:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 22:00:00.035485 :C5EF2705:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=217 time=804051330 2025-08-28 22:00:00.035486 :C5EF2706:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 22:00:00.035486 :C5EF2707:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 22:00:00.035486 :C5EF2708:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 22:00:00.035486 :C5EF2709:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 22:00:00.035487 :C5EF270A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 22:00:00.035974 :C5EF270B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.036018 :C5EF2712:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:00.036094 :C5EF2726:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.040051 :C5EF2735:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.041266 :C5EF273D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.041293 :C5EF2740:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.041335 :C5EF274B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-28 22:00:00.043130 :C5EF2756:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.043159 :C5EF2759:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.043199 :C5EF2764:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-28 22:00:00.043644 :C5EF276F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.046086 :C5EF2777:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.051326 :C5EF277F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.052470 :C5EF2787:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.057420 :C5EF278F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.058816 :C5EF2797:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.061795 :C5EF279F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.063086 :C5EF27A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.066080 :C5EF27AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.069451 :C5EF27B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.069812 :C5EF27C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.069856 :C5EF27C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-28 22:00:00.069870 :C5EF27CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-28 22:00:00.071854 :C5EF27D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.071899 :C5EF27D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-28 22:00:00.071910 :C5EF27D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-28 22:00:00.073883 :C5EF27DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.073952 :C5EF27DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-28 22:00:00.073969 :C5EF27DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-28 22:00:00.074149 :C5EF27E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.074163 :C5EF27E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-28 22:00:00.074201 :C5EF27F2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-28 22:00:00.075782 :C5EF27FD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-28 22:00:02.031214 :C5EF28B6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-28 22:00:02.026 2025-08-28 22:00:02.031217 :C5EF28B7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19592 pso_num=24 pso_serial#=182 2025-08-28 22:00:02.031350 :C5EF28B8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19592 osp_idx=22 osp_ver=810837 osp_pg=0 (spawn #535814) 2025-08-28 22:00:02.031351 :C5EF28B9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 22:00:02.031351 :C5EF28BA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=218 time=804053330 2025-08-28 22:00:02.031351 :C5EF28BB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 22:00:02.031352 :C5EF28BC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 22:00:02.031352 :C5EF28BD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 22:00:02.031352 :C5EF28BE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 22:00:02.031353 :C5EF28BF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 22:00:02.031907 :C5EF28C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.037178 :C5EF28DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.037226 :C5EF28E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-28 22:00:02.048687 :C5EF2960:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.053879 :C5EF2985:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.054675 :C5EF29A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.060144 :C5EF2B03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.060293 :C5EF2B12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.061034 :C5EF2B24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.061232 :C5EF2B2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-28 22:00:02.062890 :C5EF2B2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.062938 :C5EF2B30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.063001 :C5EF2B3D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:02.166835 :C5EF2C27:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:02.168922 :C5EF2C29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.168958 :C5EF2C2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-28 22:00:02.171525 :C5EF2C32:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-28 22:00:02.172077 :C5EF2C33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.175109 :C5EF2C3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.175185 :C5EF2C3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-28 22:00:02.175270 :C5EF2C4B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:04.020740 :C5EF2FA4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:04.020740*:C5EF2FA6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1756389603 eid=16786950 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-28 22:00:04.020740*:C5EF2FA7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-08-28 22:00:04.020740*:C5EF2FA8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-08-28 22:00:04.020740*:C5EF2FA9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-08-28 22:00:04.020740*:C5EF2FAA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-08-28 22:00:04.020740*:C5EF2FAB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-08-28 22:00:04.022902 :C5EF2FAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:04.022933 :C5EF2FAF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-28 22:00:04.032309 :C5EF2FB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:04.032968 :C5EF2FBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:04.033627 :C5EF2FC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:04.033815 :C5EF2FCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-28 22:00:04.036577 :C5EF2FE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-28 22:00:04.036615 :C5EF2FE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-28 22:00:04.036752 :C5EF2FF7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:04.771670 :C5EF30F0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 19592, J000)