Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831060005\orcl_j000_22080_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:35082M/63366M, Ph+PgF:40058M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22080, image: ORACLE.EXE (J000) *** 2025-08-31 06:00:05.160 *** SESSION ID:(201.7613) 2025-08-31 06:00:05.160 *** 2025-08-31 06:00:05.160 Process diagnostic dump for ORACLE.EXE (J000), OS id=22080, pid: 25, proc_ser: 9, sid: 201, sess_ser: 7613 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22080 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.192005 sec, exc=0.192005 sec, total=0.192005 sec wait times: max=0.500000 sec, heur=4.692590 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.000006 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.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.000009 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.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 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000008 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.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.000008 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.510097 sec, exc=0.510097 sec, total=0.510097 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 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.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.000010 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.499977 sec, exc=0.499977 sec, total=0.499977 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 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.490423 sec, exc=0.490423 sec, total=0.490423 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 7613 --------------------------------------------------- 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, 06:00:00 - 06:00:05] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 06:00:05.160 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22080, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 05:50:09.621035 :C6F726B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:50:09.621035 :C6F726B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:50:09.621036 :C6F726B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:50:09.621036 :C6F726B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:50:09.621037 :C6F726B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:50:09.621544 :C6F726BA: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-08-31 05:50:39.724807 :C6F73060:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:51:09.695805 :C6F739E8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:51:09.685 2025-08-31 05:51:09.695808 :C6F739E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19396 pso_num=25 pso_serial#=254 2025-08-31 05:51:09.695987 :C6F739EA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19396 osp_idx=23 osp_ver=270921 osp_pg=0 (spawn #543579) 2025-08-31 05:51:09.695987 :C6F739EB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:51:09.695988 :C6F739EC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=197 time=1005120987 2025-08-31 05:51:09.695988 :C6F739ED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:51:09.695988 :C6F739EE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:51:09.695988 :C6F739EF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:51:09.695989 :C6F739F0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:51:09.695989 :C6F739F1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:51:09.696461 :C6F739F2: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-08-31 05:51:39.775452 :C6F743D9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:52:09.750277 :C6F74D3B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:52:09.738 2025-08-31 05:52:09.750279 :C6F74D3C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22084 pso_num=25 pso_serial#=255 2025-08-31 05:52:09.750395 :C6F74D3D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22084 osp_idx=23 osp_ver=270922 osp_pg=0 (spawn #543581) 2025-08-31 05:52:09.750396 :C6F74D3E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:52:09.750396 :C6F74D3F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=198 time=1005181049 2025-08-31 05:52:09.750397 :C6F74D40:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:52:09.750397 :C6F74D41:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:52:09.750397 :C6F74D42:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:52:09.750398 :C6F74D43:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:52:09.750398 :C6F74D44:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:52:09.750936 :C6F74D45: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-08-31 05:52:39.816289 :C6F756E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:53:09.823930 :C6F76093:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:53:09.813 2025-08-31 05:53:09.823931 :C6F76094:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20104 pso_num=25 pso_serial#=1 2025-08-31 05:53:09.824051 :C6F76095:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20104 osp_idx=23 osp_ver=270923 osp_pg=0 (spawn #543583) 2025-08-31 05:53:09.824052 :C6F76096:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:53:09.824052 :C6F76097:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=199 time=1005241127 2025-08-31 05:53:09.824053 :C6F76098:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:53:09.824053 :C6F76099:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:53:09.824053 :C6F7609A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:53:09.824054 :C6F7609B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:53:09.824054 :C6F7609C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:53:09.824542 :C6F7609D: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-08-31 05:53:39.882833 :C6F76AA2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:54:09.886817 :C6F77424:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:54:09.875 2025-08-31 05:54:09.886819 :C6F77425:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20908 pso_num=25 pso_serial#=2 2025-08-31 05:54:09.886948 :C6F77426:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20908 osp_idx=23 osp_ver=270924 osp_pg=0 (spawn #543585) 2025-08-31 05:54:09.886949 :C6F77427:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:54:09.886949 :C6F77428:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=200 time=1005301190 2025-08-31 05:54:09.886950 :C6F77429:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:54:09.886950 :C6F7742A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:54:09.886950 :C6F7742B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:54:09.886951 :C6F7742C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:54:09.886951 :C6F7742D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:54:09.887440 :C6F7742E: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-08-31 05:54:39.970285 :C6F77DFC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:54:54.952371 :C6F782D4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:54:54.940 2025-08-31 05:54:54.952373 :C6F782D5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23484 pso_num=25 pso_serial#=3 2025-08-31 05:54:54.952516 :C6F782D6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23484 osp_idx=23 osp_ver=270925 osp_pg=0 (spawn #543587) 2025-08-31 05:54:54.952517 :C6F782D7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:54:54.952518 :C6F782D8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=201 time=1005346252 2025-08-31 05:54:54.952518 :C6F782D9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:54:54.952519 :C6F782DA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:54:54.952519 :C6F782DB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:54:54.952519 :C6F782DC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:54:54.952519 :C6F782DD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:54:54.953006 :C6F782DE: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-08-31 05:55:09.971375 :C6F787A8: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-08-31 05:55:40.070686 :C6F7921B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:56:10.071047 :C6F79BC1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:56:10.059 2025-08-31 05:56:10.071049 :C6F79BC2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23260 pso_num=25 pso_serial#=4 2025-08-31 05:56:10.071186 :C6F79BC3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23260 osp_idx=23 osp_ver=270926 osp_pg=0 (spawn #543590) 2025-08-31 05:56:10.071187 :C6F79BC4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:56:10.071187 :C6F79BC5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=202 time=1005421362 2025-08-31 05:56:10.071188 :C6F79BC6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:56:10.071189 :C6F79BC7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:56:10.071189 :C6F79BC8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:56:10.071190 :C6F79BC9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:56:10.071190 :C6F79BCA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:56:10.071659 :C6F79BCB: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-08-31 05:56:40.151223 :C6F7A576:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:57:10.126640 :C6F7AF0E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:57:10.115 2025-08-31 05:57:10.126641 :C6F7AF0F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21868 pso_num=25 pso_serial#=5 2025-08-31 05:57:10.126761 :C6F7AF10:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21868 osp_idx=23 osp_ver=270927 osp_pg=0 (spawn #543592) 2025-08-31 05:57:10.126762 :C6F7AF11:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:57:10.126763 :C6F7AF12:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=203 time=1005481424 2025-08-31 05:57:10.126763 :C6F7AF13:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:57:10.126764 :C6F7AF14:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:57:10.126764 :C6F7AF15:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:57:10.126764 :C6F7AF16:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:57:10.126764 :C6F7AF17:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:57:10.127247 :C6F7AF18: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-08-31 05:57:40.187434 :C6F7B8DF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:58:10.207114 :C6F7C278:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:58:10.196 2025-08-31 05:58:10.207116 :C6F7C279:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18680 pso_num=25 pso_serial#=6 2025-08-31 05:58:10.207237 :C6F7C27A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18680 osp_idx=23 osp_ver=270928 osp_pg=0 (spawn #543594) 2025-08-31 05:58:10.207238 :C6F7C27B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:58:10.207238 :C6F7C27C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=204 time=1005541502 2025-08-31 05:58:10.207238 :C6F7C27D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:58:10.207239 :C6F7C27E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:58:10.207239 :C6F7C27F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:58:10.207239 :C6F7C280:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:58:10.207240 :C6F7C281:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:58:10.207721 :C6F7C282: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-08-31 05:58:40.299539 :C6F7CD52:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 05:59:10.277911 :C6F7D704:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 05:59:10.266 2025-08-31 05:59:10.277913 :C6F7D705:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20836 pso_num=25 pso_serial#=7 2025-08-31 05:59:10.278029 :C6F7D706:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20836 osp_idx=23 osp_ver=270929 osp_pg=0 (spawn #543597) 2025-08-31 05:59:10.278030 :C6F7D707:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:59:10.278031 :C6F7D708:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=205 time=1005601580 2025-08-31 05:59:10.278031 :C6F7D709:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:59:10.278034 :C6F7D70A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:59:10.278034 :C6F7D70B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:59:10.278035 :C6F7D70C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:59:10.278035 :C6F7D70D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:59:10.278531 :C6F7D70E: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-08-31 05:59:40.370100 :C6F7E0B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 06:00:00.033717 :C6F7E723:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 06:00:00.025 2025-08-31 06:00:00.033719 :C6F7E724:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22576 pso_num=25 pso_serial#=8 2025-08-31 06:00:00.033854 :C6F7E725:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22576 osp_idx=23 osp_ver=270930 osp_pg=0 (spawn #543599) 2025-08-31 06:00:00.033855 :C6F7E726:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 06:00:00.033856 :C6F7E727:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=1005651330 2025-08-31 06:00:00.033856 :C6F7E728:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 06:00:00.033856 :C6F7E729:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 06:00:00.033857 :C6F7E72A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 06:00:00.033857 :C6F7E72B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 06:00:00.033857 :C6F7E72C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 06:00:00.034360 :C6F7E72D: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-08-31 06:00:00.034401 :C6F7E734: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-08-31 06:00:00.034477 :C6F7E747: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-08-31 06:00:00.038403 :C6F7E756: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-08-31 06:00:00.039547 :C6F7E75E: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-08-31 06:00:00.039571 :C6F7E761: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-08-31 06:00:00.039608 :C6F7E76C: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-08-31 06:00:00.041473 :C6F7E777: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-08-31 06:00:00.041499 :C6F7E77A: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-08-31 06:00:00.041535 :C6F7E785: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-08-31 06:00:00.042084 :C6F7E790: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-08-31 06:00:00.044555 :C6F7E798: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-08-31 06:00:00.049819 :C6F7E7A0: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-08-31 06:00:00.055344 :C6F7E7A8: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-08-31 06:00:00.056780 :C6F7E7B0: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-08-31 06:00:00.059670 :C6F7E7B8: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-08-31 06:00:00.060993 :C6F7E7C0: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-08-31 06:00:00.063951 :C6F7E7C8: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-08-31 06:00:00.067822 :C6F7E7D0: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-08-31 06:00:00.068217 :C6F7E7DF: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-08-31 06:00:00.068264 :C6F7E7E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-31 06:00:00.068277 :C6F7E7E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-31 06:00:00.071132 :C6F7E7E9: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-08-31 06:00:00.071418 :C6F7E7F8: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-08-31 06:00:00.071462 :C6F7E7FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-31 06:00:00.071476 :C6F7E7FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-31 06:00:00.074236 :C6F7E802: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-08-31 06:00:00.074530 :C6F7E811: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-08-31 06:00:00.074578 :C6F7E814:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-31 06:00:00.074592 :C6F7E815:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-31 06:00:00.074764 :C6F7E81B: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-08-31 06:00:00.074775 :C6F7E81E: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-08-31 06:00:00.074798 :C6F7E82A: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-08-31 06:00:00.076284 :C6F7E834:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 06:00:00.327102 :C6F7E85B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 06:00:00.322 2025-08-31 06:00:00.327104 :C6F7E85C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22080 pso_num=25 pso_serial#=9 2025-08-31 06:00:00.327236 :C6F7E85D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22080 osp_idx=23 osp_ver=270931 osp_pg=0 (spawn #543600) 2025-08-31 06:00:00.327237 :C6F7E85E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 06:00:00.327238 :C6F7E85F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=1005651627 2025-08-31 06:00:00.327238 :C6F7E860:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 06:00:00.327238 :C6F7E861:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 06:00:00.327239 :C6F7E862:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 06:00:00.327239 :C6F7E863:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 06:00:00.327239 :C6F7E864:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 06:00:00.327737 :C6F7E865: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-08-31 06:00:00.333189 :C6F7E892: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-08-31 06:00:00.333227 :C6F7E895:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-31 06:00:00.344202 :C6F7E8E5: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-08-31 06:00:00.349303 :C6F7E90D: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-08-31 06:00:00.350286 :C6F7E932: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-08-31 06:00:00.355844 :C6F7E957: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-08-31 06:00:00.355990 :C6F7E966: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-08-31 06:00:00.356666 :C6F7E96E: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-08-31 06:00:00.356851 :C6F7E976:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-31 06:00:00.358336 :C6F7E977: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-08-31 06:00:00.358379 :C6F7E97A: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-08-31 06:00:00.358456 :C6F7E987: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-08-31 06:00:00.462510 :C6F7EBF9: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-08-31 06:00:00.464612 :C6F7EBFF: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-08-31 06:00:00.464652 :C6F7EC02:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-31 06:00:00.467072 :C6F7EC08:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-31 06:00:00.470474 :C6F7EC09: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-08-31 06:00:00.470566 :C6F7EC0C: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-08-31 06:00:00.470661 :C6F7EC19: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: 22080, J000)