Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903220008\orcl_j001_22456_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903220008\orcl_j001_22456_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:35076M/63366M, Ph+PgF:39971M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 22456, image: ORACLE.EXE (J001) *** 2025-09-03 22:00:08.221 *** SESSION ID:(193.38429) 2025-09-03 22:00:08.221 *** 2025-09-03 22:00:08.221 Process diagnostic dump for ORACLE.EXE (J001), OS id=22456, pid: 24, proc_ser: 76, sid: 193, sess_ser: 38429 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22456 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.203031 sec, exc=0.203031 sec, total=0.203031 sec wait times: max=0.500000 sec, heur=4.204827 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=7 seq_num=8 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500126 sec, exc=0.500126 sec, total=0.500126 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.501611 sec, exc=0.501611 sec, total=0.501611 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 38429 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:04 - 22:00:08] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-09-03 22:00:08.236 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 22456, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-03 21:51:29.357826 :C898F85D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=61 time=1321940658 2025-09-03 21:51:29.357826 :C898F85E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:51:29.357827 :C898F85F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:51:29.357827 :C898F860:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:51:29.357827 :C898F861:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:51:29.357828 :C898F862:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:51:29.358389 :C898F863: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-09-03 21:51:59.440673 :C8990266:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:52:14.436210 :C899075F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:52:14.425 2025-09-03 21:52:14.436211 :C8990760:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24468 pso_num=24 pso_serial#=68 2025-09-03 21:52:14.436339 :C8990761:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24468 osp_idx=22 osp_ver=837842 osp_pg=0 (spawn #555798) 2025-09-03 21:52:14.436340 :C8990762:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:52:14.436340 :C8990763:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=62 time=1321985737 2025-09-03 21:52:14.436341 :C8990764:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:52:14.436341 :C8990765:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:52:14.436342 :C8990766:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:52:14.436342 :C8990767:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:52:14.436342 :C8990768:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:52:14.436815 :C8990769: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-09-03 21:52:29.427485 :C8990C90: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-09-03 21:52:59.517812 :C899176D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:53:29.524676 :C8992151:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:53:29.513 2025-09-03 21:53:29.524678 :C8992152:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22500 pso_num=24 pso_serial#=69 2025-09-03 21:53:29.524792 :C8992153:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22500 osp_idx=22 osp_ver=837843 osp_pg=0 (spawn #555800) 2025-09-03 21:53:29.524793 :C8992154:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:53:29.524793 :C8992155:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=63 time=1322060815 2025-09-03 21:53:29.524794 :C8992156:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:53:29.524794 :C8992157:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:53:29.524795 :C8992158:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:53:29.524795 :C8992159:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:53:29.524795 :C899215A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:53:29.525279 :C899215B: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-09-03 21:53:59.598566 :C8992AF4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:54:29.591206 :C89934DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:54:29.579 2025-09-03 21:54:29.591208 :C89934E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20680 pso_num=24 pso_serial#=70 2025-09-03 21:54:29.591336 :C89934E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20680 osp_idx=22 osp_ver=837844 osp_pg=0 (spawn #555802) 2025-09-03 21:54:29.591337 :C89934E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:54:29.591337 :C89934E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=64 time=1322120893 2025-09-03 21:54:29.591338 :C89934E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:54:29.591338 :C89934E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:54:29.591339 :C89934E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:54:29.591339 :C89934E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:54:29.591340 :C89934E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:54:29.591858 :C89934E9: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-09-03 21:54:59.663098 :C8993EB7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:55:29.701178 :C8994912:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:55:29.689 2025-09-03 21:55:29.701180 :C8994913:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21272 pso_num=24 pso_serial#=71 2025-09-03 21:55:29.701303 :C8994914:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21272 osp_idx=22 osp_ver=837845 osp_pg=0 (spawn #555804) 2025-09-03 21:55:29.701303 :C8994915:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:55:29.701304 :C8994916:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=65 time=1322181002 2025-09-03 21:55:29.701304 :C8994917:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:55:29.701304 :C8994918:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:55:29.701305 :C8994919:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:55:29.701305 :C899491A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:55:29.701306 :C899491B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:55:29.701837 :C899491C: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-09-03 21:55:59.807492 :C899533D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:56:04.449563 :C89954DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:56:04.437 2025-09-03 21:56:04.449565 :C89954E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19760 pso_num=24 pso_serial#=72 2025-09-03 21:56:04.449701 :C89954E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19760 osp_idx=22 osp_ver=837846 osp_pg=0 (spawn #555806) 2025-09-03 21:56:04.449702 :C89954E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:56:04.449702 :C89954E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=66 time=1322215752 2025-09-03 21:56:04.449703 :C89954E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:56:04.449703 :C89954E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:56:04.449703 :C89954E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:56:04.449704 :C89954E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:56:04.449704 :C89954E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:56:04.450195 :C89954E9: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-09-03 21:56:04.450233 :C89954EF: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-09-03 21:56:04.454336 :C8995518:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-03 21:56:04.454377 :C8995519:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-03 21:56:04.454625 :C899551A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-03 21:56:04.454640 :C899551B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-03 21:56:04.454850 :C899551C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-03 21:56:04.454870 :C899551D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-03 21:56:04.455149 :C899551E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-03 21:56:04.455162 :C899551F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-03 21:56:04.455370 :C8995520:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-03 21:56:04.455383 :C8995521:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-03 21:56:04.455592 :C8995522:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-03 21:56:04.455605 :C8995523:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-03 21:56:04.457869 :C8995524:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:56:29.764866 :C8995DC0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:56:29.753 2025-09-03 21:56:29.764868 :C8995DC1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21892 pso_num=24 pso_serial#=73 2025-09-03 21:56:29.765036 :C8995DC2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21892 osp_idx=22 osp_ver=837847 osp_pg=0 (spawn #555808) 2025-09-03 21:56:29.765036 :C8995DC3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:56:29.765037 :C8995DC4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=67 time=1322241065 2025-09-03 21:56:29.765037 :C8995DC5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:56:29.765038 :C8995DC6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:56:29.765038 :C8995DC7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:56:29.765038 :C8995DC8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:56:29.765039 :C8995DC9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:56:29.765571 :C8995DCA: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-09-03 21:56:59.821942 :C899679E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:57:29.847419 :C899717E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:57:29.836 2025-09-03 21:57:29.847421 :C899717F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22328 pso_num=24 pso_serial#=74 2025-09-03 21:57:29.847533 :C8997180:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22328 osp_idx=22 osp_ver=837848 osp_pg=0 (spawn #555810) 2025-09-03 21:57:29.847534 :C8997181:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:57:29.847534 :C8997182:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=68 time=1322301143 2025-09-03 21:57:29.847535 :C8997183:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:57:29.847536 :C8997184:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:57:29.847536 :C8997185:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:57:29.847536 :C8997186:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:57:29.847537 :C8997187:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:57:29.848049 :C8997188: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-09-03 21:57:59.920981 :C8997BDE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:58:29.934945 :C89985D7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:58:29.923 2025-09-03 21:58:29.934947 :C89985D8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7652 pso_num=24 pso_serial#=75 2025-09-03 21:58:29.935078 :C89985D9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7652 osp_idx=22 osp_ver=837849 osp_pg=0 (spawn #555812) 2025-09-03 21:58:29.935079 :C89985DA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:58:29.935079 :C89985DB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=69 time=1322361237 2025-09-03 21:58:29.935080 :C89985DC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:58:29.935081 :C89985DD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:58:29.935081 :C89985DE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:58:29.935082 :C89985DF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:58:29.935082 :C89985E0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:58:29.935614 :C89985E1: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-09-03 21:59:00.035713 :C8998FE0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-03 21:59:30.049907 :C8999990:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-03 21:59:30.038 2025-09-03 21:59:30.049910 :C8999991:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22456 pso_num=24 pso_serial#=76 2025-09-03 21:59:30.050032 :C8999992:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22456 osp_idx=22 osp_ver=837850 osp_pg=0 (spawn #555815) 2025-09-03 21:59:30.050033 :C8999993:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:59:30.050034 :C8999994:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=70 time=1322421346 2025-09-03 21:59:30.050034 :C8999995:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:59:30.050035 :C8999996:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:59:30.050036 :C8999997:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:59:30.050036 :C8999998:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:59:30.050037 :C8999999:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:59:30.050541 :C899999A: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-09-03 22:00:00.071755 :C899A458: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-09-03 22:00:00.071755*:C899A477:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5B898 estart=1756907999 eid=16787317 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-03 22:00:00.071755*:C899A478:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 pubTabIdxCnt=0 2025-09-03 22:00:00.071755*:C899A479:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5B898 2025-09-03 22:00:00.071755*:C899A47A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5B898 2025-09-03 22:00:00.071755*:C899A47B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 xsc->flg4=65792 flags=2 2025-09-03 22:00:00.071755*:C899A47C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5B898 2025-09-03 22:00:00.077169 :C899A487: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-09-03 22:00:00.077201 :C899A48A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-09-03 22:00:00.082118 :C899A4BE:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-03 22:00:00.082713 :C899A4BF:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 33a9120000000000 2025-09-03 22:00:00.084432 :C899A4DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.084513 :C899A4F0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.084521 :C899A4F5:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-09-03 22:00:00.084525 :C899A4F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.084559 :C899A502:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.085276 :C899A50E:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 236426959) 2025-09-03 22:00:00.085280 :C899A50F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.085303 :C899A51E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.085312 :C899A527:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1) 2025-09-03 22:00:00.085315 :C899A528:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.085342 :C899A535:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.085346 :C899A53F:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560) 2025-09-03 22:00:00.085349 :C899A541:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.085391 :C899A561:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.085754 :C899A5EB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.085763 :C899A5EF:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-03 22:00:00.086559 :C899A5F9:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 34a9120000000000 2025-09-03 22:00:00.086951 :C899A601:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 236426959) 2025-09-03 22:00:00.086955 :C899A602:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.086986 :C899A613:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-03 22:00:00.097244 :C899A658:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-09-03 22:00:00.098799 :C899A659: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-09-03 22:00:00.098848 :C899A65C: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-09-03 22:00:00.098934 :C899A669: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-09-03 22:00:00.181013 :C899A6A7: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-09-03 22:00:04.013715 :C899AC90: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-09-03 22:00:04.013715*:C899AC94:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1756908003 eid=16787323 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-03 22:00:04.013715*:C899AC95:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-09-03 22:00:04.013715*:C899AC96:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-09-03 22:00:04.013715*:C899AC97:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-09-03 22:00:04.013715*:C899AC98:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-09-03 22:00:04.013715*:C899AC99:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-09-03 22:00:04.015959 :C899AC9A: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-09-03 22:00:04.015997 :C899AC9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-09-03 22:00:04.026546 :C899ACA3: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-09-03 22:00:04.027486 :C899ACAB: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-09-03 22:00:04.028619 :C899ACB3: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-09-03 22:00:04.028842 :C899ACBB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-09-03 22:00:04.030371 :C899ACBC: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-09-03 22:00:04.030416 :C899ACBF: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-09-03 22:00:04.030512 :C899ACCC: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 22456, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de