Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250715220005\orcl_j000_15780_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:36321M/63366M, Ph+PgF:42294M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15780, image: ORACLE.EXE (J000) *** 2025-07-15 22:00:05.452 *** SESSION ID:(201.44953) 2025-07-15 22:00:05.452 *** 2025-07-15 22:00:05.452 Process diagnostic dump for ORACLE.EXE (J000), OS id=15780, pid: 25, proc_ser: 114, sid: 201, sess_ser: 44953 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15780 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) 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.440423 sec, exc=0.440423 sec, total=0.440423 sec wait times: max=0.500000 sec, heur=1.426854 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.000007 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.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.486411 sec, exc=0.486411 sec, total=0.486411 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 44953 --------------------------------------------------- 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:04 - 22:00:05] 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-07-15 22:00:05.452 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15780, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-15 21:52:19.848609 :B27D2DA6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17004 osp_idx=23 osp_ver=198097 osp_pg=0 (spawn #389149) 2025-07-15 21:52:19.848610 :B27D2DA7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:52:19.848610 :B27D2DA8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=1297115736 2025-07-15 21:52:19.848611 :B27D2DA9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:52:19.848612 :B27D2DAA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:52:19.848612 :B27D2DAB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:52:19.848612 :B27D2DAC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:52:19.848613 :B27D2DAD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:52:19.849095 :B27D2DAE: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-07-15 21:52:49.924158 :B27D3759:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:53:19.949924 :B27D4121:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:53:19.939 2025-07-15 21:53:19.949925 :B27D4122:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9248 pso_num=25 pso_serial#=106 2025-07-15 21:53:19.950057 :B27D4123:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9248 osp_idx=23 osp_ver=198098 osp_pg=0 (spawn #389151) 2025-07-15 21:53:19.950057 :B27D4124:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:53:19.950058 :B27D4125:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=1297175845 2025-07-15 21:53:19.950058 :B27D4126:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:53:19.950058 :B27D4127:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:53:19.950059 :B27D4128:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:53:19.950059 :B27D4129:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:53:19.950059 :B27D412A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:53:19.950541 :B27D412B: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-07-15 21:53:50.046886 :B27D4B56:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:54:20.064827 :B27D54F8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:54:20.053 2025-07-15 21:54:20.064829 :B27D54F9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7196 pso_num=25 pso_serial#=107 2025-07-15 21:54:20.064954 :B27D54FA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7196 osp_idx=23 osp_ver=198099 osp_pg=0 (spawn #389153) 2025-07-15 21:54:20.064954 :B27D54FB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:54:20.064955 :B27D54FC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=1297235954 2025-07-15 21:54:20.064956 :B27D54FD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:54:20.064956 :B27D54FE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:54:20.064956 :B27D54FF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:54:20.064957 :B27D5500:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:54:20.064957 :B27D5501:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:54:20.065450 :B27D5502: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-07-15 21:54:50.154078 :B27D5ECC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:55:20.145109 :B27D687A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:55:20.133 2025-07-15 21:55:20.145111 :B27D687B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17088 pso_num=25 pso_serial#=108 2025-07-15 21:55:20.145229 :B27D687C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17088 osp_idx=23 osp_ver=198100 osp_pg=0 (spawn #389155) 2025-07-15 21:55:20.145230 :B27D687D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:55:20.145230 :B27D687E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=1297296033 2025-07-15 21:55:20.145231 :B27D687F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:55:20.145231 :B27D6880:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:55:20.145231 :B27D6881:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:55:20.145232 :B27D6882:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:55:20.145232 :B27D6883:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:55:20.145742 :B27D6884: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-07-15 21:55:45.160954 :B27D70E2: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-07-15 21:56:15.272373 :B27D7A6D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:56:20.231210 :B27D7C26:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:56:20.219 2025-07-15 21:56:20.231212 :B27D7C27:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10368 pso_num=25 pso_serial#=109 2025-07-15 21:56:20.231337 :B27D7C28:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10368 osp_idx=23 osp_ver=198101 osp_pg=0 (spawn #389157) 2025-07-15 21:56:20.231338 :B27D7C29:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:56:20.231338 :B27D7C2A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=1297356126 2025-07-15 21:56:20.231338 :B27D7C2B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:56:20.231339 :B27D7C2C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:56:20.231339 :B27D7C2D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:56:20.231339 :B27D7C2E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:56:20.231339 :B27D7C2F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:56:20.231835 :B27D7C30: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-07-15 21:56:50.327003 :B27D85E8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:57:20.396930 :B27D8FBA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:57:20.397 2025-07-15 21:57:20.396932 :B27D8FBB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15404 pso_num=25 pso_serial#=110 2025-07-15 21:57:20.397066 :B27D8FBC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15404 osp_idx=23 osp_ver=198102 osp_pg=0 (spawn #389159) 2025-07-15 21:57:20.397067 :B27D8FBD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:57:20.397067 :B27D8FBE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=1297416283 2025-07-15 21:57:20.397067 :B27D8FBF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:57:20.397068 :B27D8FC0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:57:20.397068 :B27D8FC1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:57:20.397069 :B27D8FC2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:57:20.397069 :B27D8FC3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:57:20.397575 :B27D8FC4: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-07-15 21:57:50.476185 :B27D9979:db_trace:ksl2.c@2324:kslwtbctx(): [10005:25:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-15 21:57:50.476197 :B27D997A:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-15 21:57:50.476198 :B27D997B:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:0] KSL WAIT END wait times (usecs) - snap=12, exc=12, tot=12 2025-07-15 21:57:50.476278 :B27D997C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:58:20.480756 :B27DA377:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:58:20.470 2025-07-15 21:58:20.480758 :B27DA378:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14888 pso_num=25 pso_serial#=111 2025-07-15 21:58:20.480894 :B27DA379:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14888 osp_idx=23 osp_ver=198103 osp_pg=0 (spawn #389162) 2025-07-15 21:58:20.480895 :B27DA37A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:58:20.480895 :B27DA37B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=1297476376 2025-07-15 21:58:20.480896 :B27DA37C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:58:20.480896 :B27DA37D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:58:20.480896 :B27DA37E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:58:20.480897 :B27DA37F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:58:20.480897 :B27DA380:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:58:20.481375 :B27DA381: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-07-15 21:58:50.583667 :B27DAE33:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 21:59:20.578876 :B27DB7CE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 21:59:20.566 2025-07-15 21:59:20.578879 :B27DB7CF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17856 pso_num=25 pso_serial#=112 2025-07-15 21:59:20.579000 :B27DB7D0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17856 osp_idx=23 osp_ver=198104 osp_pg=0 (spawn #389164) 2025-07-15 21:59:20.579001 :B27DB7D1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 21:59:20.579002 :B27DB7D2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=1297536470 2025-07-15 21:59:20.579002 :B27DB7D3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 21:59:20.579003 :B27DB7D4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 21:59:20.579003 :B27DB7D5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 21:59:20.579004 :B27DB7D6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 21:59:20.579004 :B27DB7D7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 21:59:20.579556 :B27DB7D8: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-07-15 21:59:50.663147 :B27DC178:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 22:00:00.034011 :B27DC4EE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 22:00:00.026 2025-07-15 22:00:00.034013 :B27DC4EF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17692 pso_num=25 pso_serial#=113 2025-07-15 22:00:00.034198 :B27DC4F0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17692 osp_idx=23 osp_ver=198105 osp_pg=0 (spawn #389166) 2025-07-15 22:00:00.034198 :B27DC4F1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 22:00:00.034199 :B27DC4F2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=1297575923 2025-07-15 22:00:00.034199 :B27DC4F3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 22:00:00.034199 :B27DC4F4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 22:00:00.034200 :B27DC4F5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 22:00:00.034200 :B27DC4F6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 22:00:00.034201 :B27DC4F7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 22:00:00.034697 :B27DC4F8: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-07-15 22:00:00.034739 :B27DC4FF: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-07-15 22:00:00.034815 :B27DC513:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.038707 :B27DC522: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-07-15 22:00:00.039885 :B27DC52A: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-07-15 22:00:00.039910 :B27DC52D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.039949 :B27DC538:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 22:00:00.041707 :B27DC543: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-07-15 22:00:00.041735 :B27DC546:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.041771 :B27DC551:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 22:00:00.042338 :B27DC55C: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-07-15 22:00:00.044768 :B27DC564: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-07-15 22:00:00.050087 :B27DC56C: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-07-15 22:00:00.055606 :B27DC574: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-07-15 22:00:00.057038 :B27DC57C: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-07-15 22:00:00.060007 :B27DC584: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-07-15 22:00:00.061252 :B27DC58C: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-07-15 22:00:00.064251 :B27DC594: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-07-15 22:00:00.067616 :B27DC59C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.068018 :B27DC5AB: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-07-15 22:00:00.068072 :B27DC5AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-07-15 22:00:00.068086 :B27DC5AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-07-15 22:00:00.070079 :B27DC5B5: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-07-15 22:00:00.070123 :B27DC5B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-07-15 22:00:00.070134 :B27DC5B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-07-15 22:00:00.072113 :B27DC5BF: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-07-15 22:00:00.072163 :B27DC5C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-07-15 22:00:00.072174 :B27DC5C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-07-15 22:00:00.072340 :B27DC5C9: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-07-15 22:00:00.072354 :B27DC5CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.072390 :B27DC5D7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-15 22:00:00.074035 :B27DC5E2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-15 22:00:00.625451 :B27DC684:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-15 22:00:00.619 2025-07-15 22:00:00.625453 :B27DC685:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15780 pso_num=25 pso_serial#=114 2025-07-15 22:00:00.625592 :B27DC686:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15780 osp_idx=23 osp_ver=198106 osp_pg=0 (spawn #389167) 2025-07-15 22:00:00.625593 :B27DC687:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-15 22:00:00.625593 :B27DC688:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=1297576517 2025-07-15 22:00:00.625594 :B27DC689:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-15 22:00:00.625594 :B27DC68A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-15 22:00:00.625594 :B27DC68B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-15 22:00:00.625595 :B27DC68C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-15 22:00:00.625595 :B27DC68D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-15 22:00:00.626102 :B27DC68E: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-07-15 22:00:00.631305 :B27DC6AD: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-07-15 22:00:00.631347 :B27DC6B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-15 22:00:00.642615 :B27DC716: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-07-15 22:00:00.648044 :B27DC740: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-07-15 22:00:00.648839 :B27DC75D: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-07-15 22:00:00.654683 :B27DC8B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.654838 :B27DC8BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 22:00:00.655616 :B27DC8D1: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-07-15 22:00:00.655810 :B27DC8D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-15 22:00:00.657301 :B27DC8DA: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-07-15 22:00:00.657341 :B27DC8DD: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-07-15 22:00:00.657411 :B27DC8EA: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 2025-07-15 22:00:00.760595 :B27DC9E2: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-07-15 22:00:00.762767 :B27DC9E6: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-07-15 22:00:00.762806 :B27DC9E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-15 22:00:00.765346 :B27DC9EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-15 22:00:00.768650 :B27DC9F0: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-07-15 22:00:00.768733 :B27DC9F3: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-07-15 22:00:00.768826 :B27DCA00: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 2025-07-15 22:00:04.015541 :B27DCE6F: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-07-15 22:00:04.015541*:B27DCE73:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1752588003 eid=16784250 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-15 22:00:04.015541*:B27DCE74:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-07-15 22:00:04.015541*:B27DCE75:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-07-15 22:00:04.015541*:B27DCE76:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-07-15 22:00:04.015541*:B27DCE77:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-07-15 22:00:04.015541*:B27DCE78:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-07-15 22:00:04.017685 :B27DCE79: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-07-15 22:00:04.017724 :B27DCE7C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-15 22:00:04.024362 :B27DCE82: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-07-15 22:00:04.025099 :B27DCE8A: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-07-15 22:00:04.025809 :B27DCE92: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-07-15 22:00:04.026001 :B27DCE9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-15 22:00:04.027474 :B27DCE9B: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-07-15 22:00:04.027535 :B27DCE9E: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-07-15 22:00:04.027602 :B27DCEAB: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: 15780, J000)