Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250618082723\orcl_j000_13328_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250618082723\orcl_j000_13328_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:36532M/63366M, Ph+PgF:42921M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 13328, image: ORACLE.EXE (J000) *** 2025-06-18 08:27:23.594 *** SESSION ID:(201.29407) 2025-06-18 08:27:23.594 *** 2025-06-18 08:27:23.594 Process diagnostic dump for ORACLE.EXE (J000), OS id=13328, pid: 25, proc_ser: 126, sid: 201, sess_ser: 29407 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13328 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.334405 sec, exc=0.334405 sec, total=0.334405 sec wait times: max=0.500000 sec, heur=5.353303 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 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=8 seq_num=9 snap_id=1 wait times: snap=0.510134 sec, exc=0.510134 sec, total=0.510134 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510091 sec, exc=0.510091 sec, total=0.510091 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.498596 sec, exc=0.498596 sec, total=0.498596 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 29407 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 08:27:18 - 08:27:23] idle wait at each sample [session created at: 08:27:18] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-18 08:27:23.594 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 13328, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-18 08:13:17.068949 :A6419C3A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:13:17.068949 :A6419C3B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:13:17.068950 :A6419C3C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:13:17.068950 :A6419C3D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:13:17.069440 :A6419C3E: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-18 08:13:47.153056 :A641A5F2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:14:17.155572 :A641AF9D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:14:17.144 2025-06-18 08:14:17.155574 :A641AF9E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16932 pso_num=25 pso_serial#=113 2025-06-18 08:14:17.155713 :A641AF9F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16932 osp_idx=23 osp_ver=160620 osp_pg=0 (spawn #297243) 2025-06-18 08:14:17.155714 :A641AFA0: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-18 08:14:17.155714 :A641AFA1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=3210200344 2025-06-18 08:14:17.155714 :A641AFA2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:14:17.155715 :A641AFA3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:14:17.155715 :A641AFA4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:14:17.155716 :A641AFA5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:14:17.155716 :A641AFA6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:14:17.156237 :A641AFA7: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-18 08:14:47.206019 :A641B941:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:15:17.222766 :A641C2BE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:15:17.211 2025-06-18 08:15:17.222769 :A641C2BF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16776 pso_num=25 pso_serial#=114 2025-06-18 08:15:17.222932 :A641C2C0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16776 osp_idx=23 osp_ver=160621 osp_pg=0 (spawn #297245) 2025-06-18 08:15:17.222933 :A641C2C1: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-18 08:15:17.222934 :A641C2C2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=3210260407 2025-06-18 08:15:17.222934 :A641C2C3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:15:17.222935 :A641C2C4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:15:17.222935 :A641C2C5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:15:17.222936 :A641C2C6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:15:17.222936 :A641C2C7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:15:17.223454 :A641C2C8: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-18 08:15:42.249584 :A641CA7B: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-18 08:15:42.249626*:A641CA7D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1750205741 eid=16915041 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-18 08:15:42.249626*:A641CA7E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-06-18 08:15:42.249626*:A641CA7F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-06-18 08:15:42.249626*:A641CA80:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-06-18 08:15:42.249626*:A641CA81:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-06-18 08:15:42.249626*:A641CA82:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-06-18 08:15:42.250256 :A641CA83: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-18 08:15:42.255729 :A641CA8B: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-18 08:15:42.256717 :A641CA93: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-18 08:15:42.256848 :A641CA98: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-18 08:16:12.336165 :A641D43E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:16:17.318850 :A641D62F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:16:17.308 2025-06-18 08:16:17.318852 :A641D630:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11524 pso_num=25 pso_serial#=115 2025-06-18 08:16:17.318987 :A641D631:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11524 osp_idx=23 osp_ver=160622 osp_pg=0 (spawn #297247) 2025-06-18 08:16:17.318988 :A641D632: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-18 08:16:17.318988 :A641D633:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=128 time=3210320500 2025-06-18 08:16:17.318989 :A641D634:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:16:17.318989 :A641D635:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:16:17.318990 :A641D636:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:16:17.318990 :A641D637:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:16:17.318991 :A641D638:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:16:17.319473 :A641D639: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-18 08:16:47.412234 :A641E022:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:17:17.376874 :A641E97D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:17:17.366 2025-06-18 08:17:17.376876 :A641E97E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14676 pso_num=25 pso_serial#=116 2025-06-18 08:17:17.377008 :A641E97F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14676 osp_idx=23 osp_ver=160623 osp_pg=0 (spawn #297250) 2025-06-18 08:17:17.377009 :A641E980: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-18 08:17:17.377010 :A641E981:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=129 time=3210380563 2025-06-18 08:17:17.377010 :A641E982:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:17:17.377010 :A641E983:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:17:17.377011 :A641E984:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:17:17.377011 :A641E985:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:17:17.377011 :A641E986:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:17:17.377481 :A641E987: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-18 08:17:47.435319 :A641F2D3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:18:17.454349 :A641FC69:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:18:17.443 2025-06-18 08:18:17.454352 :A641FC6A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15256 pso_num=25 pso_serial#=117 2025-06-18 08:18:17.454483 :A641FC6B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15256 osp_idx=23 osp_ver=160624 osp_pg=0 (spawn #297252) 2025-06-18 08:18:17.454484 :A641FC6C: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-18 08:18:17.454484 :A641FC6D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=3210440641 2025-06-18 08:18:17.454485 :A641FC6E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:18:17.454485 :A641FC6F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:18:17.454485 :A641FC70:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:18:17.454486 :A641FC71:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:18:17.454486 :A641FC72:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:18:17.454965 :A641FC73: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-18 08:18:47.485550 :A64206B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:19:17.537049 :A6421020:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:19:17.526 2025-06-18 08:19:17.537051 :A6421021:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10976 pso_num=25 pso_serial#=118 2025-06-18 08:19:17.537189 :A6421022:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10976 osp_idx=23 osp_ver=160625 osp_pg=0 (spawn #297254) 2025-06-18 08:19:17.537190 :A6421023: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-18 08:19:17.537190 :A6421024:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=3210500719 2025-06-18 08:19:17.537190 :A6421025:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:19:17.537191 :A6421026:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:19:17.537191 :A6421027:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:19:17.537191 :A6421028:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:19:17.537192 :A6421029:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:19:17.537674 :A642102A: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-18 08:19:47.587396 :A642199C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:20:17.619295 :A642233E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:20:17.608 2025-06-18 08:20:17.619297 :A642233F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17160 pso_num=25 pso_serial#=119 2025-06-18 08:20:17.619439 :A6422340:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17160 osp_idx=23 osp_ver=160626 osp_pg=0 (spawn #297256) 2025-06-18 08:20:17.619439 :A6422341: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-18 08:20:17.619440 :A6422342:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=132 time=3210560797 2025-06-18 08:20:17.619440 :A6422343:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:20:17.619440 :A6422344:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:20:17.619441 :A6422345:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:20:17.619441 :A6422346:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:20:17.619441 :A6422347:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:20:17.619926 :A6422348: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-18 08:20:47.728656 :A6422CDA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:21:17.728974 :A6423634:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:21:17.718 2025-06-18 08:21:17.728976 :A6423635:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17064 pso_num=25 pso_serial#=120 2025-06-18 08:21:17.729106 :A6423636:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17064 osp_idx=23 osp_ver=160627 osp_pg=0 (spawn #297258) 2025-06-18 08:21:17.729107 :A6423637: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-18 08:21:17.729107 :A6423638:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=133 time=3210620907 2025-06-18 08:21:17.729108 :A6423639:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:21:17.729109 :A642363A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:21:17.729109 :A642363B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:21:17.729110 :A642363C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:21:17.729110 :A642363D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:21:17.729679 :A642363E: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-18 08:21:47.812025 :A642400C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:22:17.818822 :A6424963:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:22:17.807 2025-06-18 08:22:17.818824 :A6424964:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16784 pso_num=25 pso_serial#=121 2025-06-18 08:22:17.818947 :A6424965:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16784 osp_idx=23 osp_ver=160628 osp_pg=0 (spawn #297260) 2025-06-18 08:22:17.818948 :A6424966: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-18 08:22:17.818948 :A6424967:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=134 time=3210681000 2025-06-18 08:22:17.818948 :A6424968:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:22:17.818949 :A6424969:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:22:17.818949 :A642496A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:22:17.818949 :A642496B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:22:17.818950 :A642496C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:22:17.819475 :A642496D: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-18 08:22:47.839567 :A64252DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:23:17.869038 :A6425C95:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:23:17.857 2025-06-18 08:23:17.869040 :A6425C96:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8812 pso_num=25 pso_serial#=122 2025-06-18 08:23:17.869168 :A6425C97:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8812 osp_idx=23 osp_ver=160629 osp_pg=0 (spawn #297262) 2025-06-18 08:23:17.869169 :A6425C98: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-18 08:23:17.869169 :A6425C99:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=135 time=3210741047 2025-06-18 08:23:17.869169 :A6425C9A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:23:17.869170 :A6425C9B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:23:17.869170 :A6425C9C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:23:17.869170 :A6425C9D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:23:17.869171 :A6425C9E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:23:17.869632 :A6425C9F: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-18 08:23:47.934945 :A64266B1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:24:17.940965 :A6427010:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:24:17.929 2025-06-18 08:24:17.940968 :A6427011:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12936 pso_num=25 pso_serial#=123 2025-06-18 08:24:17.941100 :A6427012:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12936 osp_idx=23 osp_ver=160630 osp_pg=0 (spawn #297265) 2025-06-18 08:24:17.941100 :A6427013: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-18 08:24:17.941101 :A6427014:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=136 time=3210801125 2025-06-18 08:24:17.941101 :A6427015:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:24:17.941102 :A6427016:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:24:17.941102 :A6427017:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:24:17.941102 :A6427018:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:24:17.941103 :A6427019:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:24:17.941587 :A642701A: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-18 08:24:48.022876 :A64279C1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:25:18.037324 :A6428351:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:25:18.026 2025-06-18 08:25:18.037326 :A6428352:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15152 pso_num=25 pso_serial#=124 2025-06-18 08:25:18.037464 :A6428353:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15152 osp_idx=23 osp_ver=160631 osp_pg=0 (spawn #297267) 2025-06-18 08:25:18.037465 :A6428354: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-18 08:25:18.037466 :A6428355:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=137 time=3210861219 2025-06-18 08:25:18.037466 :A6428356:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:25:18.037467 :A6428357:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:25:18.037467 :A6428358:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:25:18.037468 :A6428359:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:25:18.037468 :A642835A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:25:18.037959 :A642835B: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-18 08:25:43.075018 :A6428B47: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-18 08:26:13.165700 :A64294BB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:26:18.134281 :A6429679:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:26:18.123 2025-06-18 08:26:18.134283 :A642967A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12296 pso_num=25 pso_serial#=125 2025-06-18 08:26:18.134413 :A642967B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12296 osp_idx=23 osp_ver=160632 osp_pg=0 (spawn #297269) 2025-06-18 08:26:18.134414 :A642967C: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-18 08:26:18.134414 :A642967D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=138 time=3210921313 2025-06-18 08:26:18.134414 :A642967E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:26:18.134415 :A642967F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:26:18.134415 :A6429680:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:26:18.134415 :A6429681:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:26:18.134416 :A6429682:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:26:18.134892 :A6429683: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-18 08:26:48.190778 :A642A086:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-18 08:27:18.235969 :A642E065:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-18 08:27:18.230 2025-06-18 08:27:18.235971 :A642E066:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13328 pso_num=25 pso_serial#=126 2025-06-18 08:27:18.236104 :A642E067:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13328 osp_idx=23 osp_ver=160633 osp_pg=0 (spawn #297273) 2025-06-18 08:27:18.236105 :A642E068: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-18 08:27:18.236105 :A642E069:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=139 time=3210981422 2025-06-18 08:27:18.236106 :A642E06A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:27:18.236106 :A642E06B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:27:18.236106 :A642E06C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:27:18.236107 :A642E06D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:27:18.236107 :A642E06E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:27:18.236611 :A642E06F: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-18 08:27:18.237225 :A642E084: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-18 08:27:18.244792 :A642E0A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-18 08:27:18.245131 :A642E0A1: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-18 08:27:18.245171 :A642E0A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-18 08:27:18.246114 :A642E0DA: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-18 08:27:18.246237 :A642E0E3: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: 13328, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de