Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250603220008\orcl_j000_9584_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250603220008\orcl_j000_9584_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:36944M/63366M, Ph+PgF:43648M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 9584, image: ORACLE.EXE (J000) *** 2025-06-03 22:00:08.853 *** SESSION ID:(201.55907) 2025-06-03 22:00:08.853 *** 2025-06-03 22:00:08.853 Process diagnostic dump for ORACLE.EXE (J000), OS id=9584, pid: 25, proc_ser: 92, sid: 201, sess_ser: 55907 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9584 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.333793 sec, exc=0.333793 sec, total=0.333793 sec wait times: max=0.500000 sec, heur=4.835112 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.000014 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.510126 sec, exc=0.510126 sec, total=0.510126 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 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=1 seq_num=2 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.491129 sec, exc=0.491129 sec, total=0.491129 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 55907 --------------------------------------------------- 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.000000 sec (max dump time=30.000000 sec) *** 2025-06-03 22:00:08.853 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 9584, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-03 21:53:27.697814 :9FEBFCA2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14020 pso_num=25 pso_serial#=82 2025-06-03 21:53:27.697961 :9FEBFCA3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14020 osp_idx=23 osp_ver=142739 osp_pg=0 (spawn #249137) 2025-06-03 21:53:27.697961 :9FEBFCA4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:53:27.697962 :9FEBFCA5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=1963366344 2025-06-03 21:53:27.697962 :9FEBFCA6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:53:27.697963 :9FEBFCA7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:53:27.697963 :9FEBFCA8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:53:27.697964 :9FEBFCA9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:53:27.697964 :9FEBFCAA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:53:27.698505 :9FEBFCAB: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-06-03 21:53:57.754443 :9FEC06EC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 21:54:27.791071 :9FEC1087:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 21:54:27.779 2025-06-03 21:54:27.791073 :9FEC1088:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15484 pso_num=25 pso_serial#=83 2025-06-03 21:54:27.791212 :9FEC1089:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15484 osp_idx=23 osp_ver=142740 osp_pg=0 (spawn #249139) 2025-06-03 21:54:27.791213 :9FEC108A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:54:27.791214 :9FEC108B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=1963426438 2025-06-03 21:54:27.791214 :9FEC108C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:54:27.791214 :9FEC108D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:54:27.791215 :9FEC108E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:54:27.791215 :9FEC108F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:54:27.791215 :9FEC1090:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:54:27.791744 :9FEC1091: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-06-03 21:54:57.875445 :9FEC1A91:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 21:55:27.859309 :9FEC2450:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 21:55:27.848 2025-06-03 21:55:27.859311 :9FEC2451:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11480 pso_num=25 pso_serial#=84 2025-06-03 21:55:27.859456 :9FEC2452:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11480 osp_idx=23 osp_ver=142741 osp_pg=0 (spawn #249142) 2025-06-03 21:55:27.859457 :9FEC2453:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:55:27.859457 :9FEC2454:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=1963486500 2025-06-03 21:55:27.859458 :9FEC2455:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:55:27.859458 :9FEC2456:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:55:27.859459 :9FEC2457:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:55:27.859459 :9FEC2458:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:55:27.859460 :9FEC2459:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:55:27.859948 :9FEC245A: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-06-03 21:55:57.952783 :9FEC2E36:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 21:56:02.902261 :9FEC2FBC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 21:56:02.891 2025-06-03 21:56:02.902263 :9FEC2FBD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15424 pso_num=25 pso_serial#=85 2025-06-03 21:56:02.902392 :9FEC2FBE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15424 osp_idx=23 osp_ver=142742 osp_pg=0 (spawn #249144) 2025-06-03 21:56:02.902393 :9FEC2FBF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:56:02.902393 :9FEC2FC0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=1963521547 2025-06-03 21:56:02.902393 :9FEC2FC1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:56:02.902394 :9FEC2FC2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:56:02.902394 :9FEC2FC3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:56:02.902395 :9FEC2FC4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:56:02.902395 :9FEC2FC5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:56:02.902907 :9FEC2FC6: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-06-03 21:56:27.935617 :9FEC3832: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-06-03 21:56:58.041944 :9FEC41F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 21:57:28.035303 :9FEC4BA1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 21:57:28.023 2025-06-03 21:57:28.035305 :9FEC4BA2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13120 pso_num=25 pso_serial#=86 2025-06-03 21:57:28.035433 :9FEC4BA3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13120 osp_idx=23 osp_ver=142743 osp_pg=0 (spawn #249146) 2025-06-03 21:57:28.035434 :9FEC4BA4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:57:28.035434 :9FEC4BA5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=1963606672 2025-06-03 21:57:28.035435 :9FEC4BA6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:57:28.035435 :9FEC4BA7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:57:28.035436 :9FEC4BA8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:57:28.035436 :9FEC4BA9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:57:28.035437 :9FEC4BAA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:57:28.035993 :9FEC4BAB: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-06-03 21:57:58.077867 :9FEC5572:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 21:58:28.104683 :9FEC5FB0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 21:58:28.094 2025-06-03 21:58:28.104685 :9FEC5FB1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15544 pso_num=25 pso_serial#=87 2025-06-03 21:58:28.104821 :9FEC5FB2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15544 osp_idx=23 osp_ver=142744 osp_pg=0 (spawn #249148) 2025-06-03 21:58:28.104822 :9FEC5FB3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:58:28.104822 :9FEC5FB4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=1963666750 2025-06-03 21:58:28.104823 :9FEC5FB5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:58:28.104823 :9FEC5FB6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:58:28.104823 :9FEC5FB7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:58:28.104824 :9FEC5FB8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:58:28.104824 :9FEC5FB9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:58:28.105301 :9FEC5FBA: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-06-03 21:58:58.179144 :9FEC6A86:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 21:59:28.202714 :9FEC7486:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 21:59:28.191 2025-06-03 21:59:28.202717 :9FEC7487:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15712 pso_num=25 pso_serial#=88 2025-06-03 21:59:28.202855 :9FEC7488:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15712 osp_idx=23 osp_ver=142745 osp_pg=0 (spawn #249150) 2025-06-03 21:59:28.202857 :9FEC7489:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 21:59:28.202857 :9FEC748A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=128 time=1963726844 2025-06-03 21:59:28.202858 :9FEC748B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 21:59:28.202858 :9FEC748C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 21:59:28.202858 :9FEC748D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 21:59:28.202858 :9FEC748E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 21:59:28.202858 :9FEC748F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 21:59:28.203363 :9FEC7490: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-06-03 21:59:58.288065 :9FEC7E6B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 22:00:00.031469 :9FEC7F1E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 22:00:00.022 2025-06-03 22:00:00.031472 :9FEC7F1F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14976 pso_num=25 pso_serial#=89 2025-06-03 22:00:00.031615 :9FEC7F20:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14976 osp_idx=23 osp_ver=142746 osp_pg=0 (spawn #249153) 2025-06-03 22:00:00.031615 :9FEC7F21:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 22:00:00.031616 :9FEC7F22:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=129 time=1963758672 2025-06-03 22:00:00.031616 :9FEC7F23:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 22:00:00.031617 :9FEC7F24:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 22:00:00.031617 :9FEC7F25:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 22:00:00.031617 :9FEC7F26:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 22:00:00.031618 :9FEC7F27:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 22:00:00.032138 :9FEC7F28: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-06-03 22:00:00.032179 :9FEC7F2F: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-06-03 22:00:00.032254 :9FEC7F43: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-06-03 22:00:00.036168 :9FEC7F52: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-06-03 22:00:00.037377 :9FEC7F5A: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-06-03 22:00:00.037403 :9FEC7F5D: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-06-03 22:00:00.037445 :9FEC7F68: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-06-03 22:00:00.039421 :9FEC7F7F: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-06-03 22:00:00.039454 :9FEC7F82: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-06-03 22:00:00.039497 :9FEC7F8D: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-06-03 22:00:00.039977 :9FEC7F98: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-06-03 22:00:00.042484 :9FEC7FA0: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-06-03 22:00:00.048055 :9FEC7FA8: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-06-03 22:00:00.053778 :9FEC7FB0: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-06-03 22:00:00.055262 :9FEC7FB8: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-06-03 22:00:00.058257 :9FEC7FC0: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-06-03 22:00:00.059611 :9FEC7FC8: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-06-03 22:00:00.062663 :9FEC7FD0: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-06-03 22:00:00.066267 :9FEC7FD8: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-06-03 22:00:00.066660 :9FEC7FE7: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-06-03 22:00:00.066708 :9FEC7FEA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-03 22:00:00.066722 :9FEC7FEB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-03 22:00:00.068900 :9FEC7FF1: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-06-03 22:00:00.068949 :9FEC7FF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-03 22:00:00.068963 :9FEC7FF5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-03 22:00:00.070952 :9FEC7FFB: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-06-03 22:00:00.070997 :9FEC7FFE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-03 22:00:00.071008 :9FEC7FFF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-03 22:00:00.071189 :9FEC8005: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-06-03 22:00:00.071203 :9FEC8008: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-06-03 22:00:00.071242 :9FEC8013: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-06-03 22:00:00.072827 :9FEC801E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 22:00:00.238383 :9FEC8021:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 22:00:00.225 2025-06-03 22:00:00.238385 :9FEC8022:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14132 pso_num=25 pso_serial#=90 2025-06-03 22:00:00.238520 :9FEC8023:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14132 (legacy spawn) 2025-06-03 22:00:00.244974 :9FEC8024: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-06-03 22:00:00.245147 :9FEC802D: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-06-03 22:00:00.245933 :9FEC802E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 22:00:00.264836 :9FEC8031:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 22:00:00.256 2025-06-03 22:00:00.264838 :9FEC8032:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4728 pso_num=25 pso_serial#=91 2025-06-03 22:00:00.264980 :9FEC8033:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=4728 (legacy spawn) 2025-06-03 22:00:00.271446 :9FEC8034: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-06-03 22:00:00.271608 :9FEC803C: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-06-03 22:00:00.272436 :9FEC803E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-03 22:00:02.028790 :9FEC80F1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-03 22:00:02.023 2025-06-03 22:00:02.028791 :9FEC80F2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9584 pso_num=25 pso_serial#=92 2025-06-03 22:00:02.028927 :9FEC80F3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9584 osp_idx=23 osp_ver=142749 osp_pg=0 (spawn #249154) 2025-06-03 22:00:02.028928 :9FEC80F4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-03 22:00:02.028929 :9FEC80F5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=1963760672 2025-06-03 22:00:02.028929 :9FEC80F6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-03 22:00:02.028930 :9FEC80F7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-03 22:00:02.028930 :9FEC80F8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-03 22:00:02.028931 :9FEC80F9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-03 22:00:02.028932 :9FEC80FA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-03 22:00:02.029423 :9FEC80FB: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-06-03 22:00:02.034642 :9FEC811A: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-06-03 22:00:02.034687 :9FEC811D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-03 22:00:02.046019 :9FEC818A: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-06-03 22:00:02.051079 :9FEC81A7: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-06-03 22:00:02.051822 :9FEC81C2: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-06-03 22:00:02.057646 :9FEC81F2: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-06-03 22:00:02.057798 :9FEC8201: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-06-03 22:00:02.058528 :9FEC8213: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-06-03 22:00:02.058717 :9FEC821B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-03 22:00:02.059182 :9FEC821C: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-06-03 22:00:02.060364 :9FEC8224: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-06-03 22:00:02.060401 :9FEC8227: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-06-03 22:00:02.060480 :9FEC8234: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-06-03 22:00:02.164509 :9FEC84BE: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-06-03 22:00:02.166723 :9FEC84C3: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-06-03 22:00:02.166763 :9FEC84C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-03 22:00:02.169298 :9FEC84CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-03 22:00:02.172611 :9FEC84CD: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-06-03 22:00:02.172717 :9FEC84D0: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-06-03 22:00:02.172803 :9FEC84DD: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-06-03 22:00:04.010449 :9FEC88ED: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-06-03 22:00:04.010449*:9FEC88F0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDC718 estart=1748959203 eid=16781668 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-03 22:00:04.010449*:9FEC88F1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 pubTabIdxCnt=0 2025-06-03 22:00:04.010449*:9FEC88F2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDC718 2025-06-03 22:00:04.010449*:9FEC88F3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDC718 2025-06-03 22:00:04.010449*:9FEC88F4:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 xsc->flg4=65792 flags=2 2025-06-03 22:00:04.010449*:9FEC88F5:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDC718 2025-06-03 22:00:04.012799 :9FEC88F6: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-06-03 22:00:04.012841 :9FEC88F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-03 22:00:04.019442 :9FEC88FF: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-06-03 22:00:04.020269 :9FEC8907: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-06-03 22:00:04.020976 :9FEC890F: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-06-03 22:00:04.021187 :9FEC8917:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-03 22:00:04.022580 :9FEC8918: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-06-03 22:00:04.022617 :9FEC891B: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-06-03 22:00:04.022700 :9FEC8928: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: 9584, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de