Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250901221833\orcl_j001_22844_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:35051M/63366M, Ph+PgF:40009M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22844, image: ORACLE.EXE (J001) *** 2025-09-01 22:18:33.558 *** SESSION ID:(201.27133) 2025-09-01 22:18:33.558 *** 2025-09-01 22:18:33.558 Process diagnostic dump for ORACLE.EXE (J001), OS id=22844, pid: 25, proc_ser: 251, sid: 201, sess_ser: 27133 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22844 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.083236 sec, exc=0.083236 sec, total=0.083236 sec wait times: max=0.500000 sec, heur=15.108510 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.000004 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=0.469826 sec, exc=0.469826 sec, total=0.469826 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.500180 sec, exc=0.500180 sec, total=0.500180 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=0.499982 sec, exc=0.499982 sec, total=0.499982 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.506730 sec, exc=0.506730 sec, total=0.506730 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=25 seq_num=26 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.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 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.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.510130 sec, exc=0.510130 sec, total=0.510130 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=21 seq_num=22 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.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=0.510114 sec, exc=0.510114 sec, total=0.510114 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time Sampled Session History of session 201 serial 27133 --------------------------------------------------- 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 --------------------------------------------------- [16 samples, 22:18:18 - 22:18:33] idle wait at each sample [session created at: 22:18:18] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-01 22:18:33.558 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22844, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-01 22:03:32.209604 :C7B57DBA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13480 pso_num=25 pso_serial#=236 2025-09-01 22:03:32.209719 :C7B57DBB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13480 osp_idx=23 osp_ver=278709 osp_pg=0 (spawn #549175) 2025-09-01 22:03:32.209720 :C7B57DBC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:03:32.209721 :C7B57DBD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=1149863502 2025-09-01 22:03:32.209721 :C7B57DBE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:03:32.209721 :C7B57DBF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:03:32.209722 :C7B57DC0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:03:32.209722 :C7B57DC1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:03:32.209722 :C7B57DC2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:03:32.210224 :C7B57DC3: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-09-01 22:04:02.278809 :C7B5878D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:04:32.304809 :C7B59558:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:04:32.291 2025-09-01 22:04:32.304812 :C7B59559:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20960 pso_num=25 pso_serial#=237 2025-09-01 22:04:32.304945 :C7B5955A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20960 osp_idx=23 osp_ver=278710 osp_pg=0 (spawn #549177) 2025-09-01 22:04:32.304946 :C7B5955B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:04:32.304946 :C7B5955C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=1149923596 2025-09-01 22:04:32.304947 :C7B5955D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:04:32.304947 :C7B5955E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:04:32.304947 :C7B5955F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:04:32.304948 :C7B59560:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:04:32.304948 :C7B59561:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:04:32.305510 :C7B59562: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-09-01 22:05:02.391823 :C7B5A04D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:05:32.373339 :C7B5B303:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:05:32.362 2025-09-01 22:05:32.373341 :C7B5B304:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18716 pso_num=25 pso_serial#=238 2025-09-01 22:05:32.373461 :C7B5B305:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18716 osp_idx=23 osp_ver=278711 osp_pg=0 (spawn #549180) 2025-09-01 22:05:32.373461 :C7B5B306:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:05:32.373462 :C7B5B307:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=1149983674 2025-09-01 22:05:32.373462 :C7B5B308:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:05:32.373462 :C7B5B309:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:05:32.373463 :C7B5B30A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:05:32.373463 :C7B5B30B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:05:32.373463 :C7B5B30C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:05:32.373957 :C7B5B30D: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-09-01 22:06:02.456919 :C7B5BDF5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:06:32.465977 :C7B5C7CC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:06:32.455 2025-09-01 22:06:32.465979 :C7B5C7CD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16876 pso_num=25 pso_serial#=239 2025-09-01 22:06:32.466109 :C7B5C7CE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16876 osp_idx=23 osp_ver=278712 osp_pg=0 (spawn #549182) 2025-09-01 22:06:32.466109 :C7B5C7CF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:06:32.466110 :C7B5C7D0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=1150043768 2025-09-01 22:06:32.466110 :C7B5C7D1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:06:32.466111 :C7B5C7D2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:06:32.466111 :C7B5C7D3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:06:32.466111 :C7B5C7D4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:06:32.466112 :C7B5C7D5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:06:32.466592 :C7B5C7D6: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-09-01 22:07:02.542385 :C7B5D1B5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:07:32.550244 :C7B5DB9D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:07:32.539 2025-09-01 22:07:32.550246 :C7B5DB9E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21176 pso_num=25 pso_serial#=240 2025-09-01 22:07:32.550383 :C7B5DB9F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21176 osp_idx=23 osp_ver=278713 osp_pg=0 (spawn #549184) 2025-09-01 22:07:32.550383 :C7B5DBA0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:07:32.550384 :C7B5DBA1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=1150103846 2025-09-01 22:07:32.550384 :C7B5DBA2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:07:32.550385 :C7B5DBA3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:07:32.550385 :C7B5DBA4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:07:32.550385 :C7B5DBA5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:07:32.550386 :C7B5DBA6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:07:32.550858 :C7B5DBA7: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-09-01 22:08:02.621537 :C7B5E5C3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:08:17.628889 :C7B5EAAB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:08:17.618 2025-09-01 22:08:17.628891 :C7B5EAAC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18048 pso_num=25 pso_serial#=241 2025-09-01 22:08:17.629024 :C7B5EAAD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18048 osp_idx=23 osp_ver=278714 osp_pg=0 (spawn #549186) 2025-09-01 22:08:17.629025 :C7B5EAAE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:08:17.629025 :C7B5EAAF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=1150148924 2025-09-01 22:08:17.629025 :C7B5EAB0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:08:17.629026 :C7B5EAB1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:08:17.629026 :C7B5EAB2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:08:17.629026 :C7B5EAB3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:08:17.629027 :C7B5EAB4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:08:17.629580 :C7B5EAB5: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-09-01 22:08:32.629467 :C7B5F008: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-09-01 22:09:02.718441 :C7B5F99D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:09:32.721817 :C7B60415:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:09:32.710 2025-09-01 22:09:32.721819 :C7B60416:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23256 pso_num=25 pso_serial#=242 2025-09-01 22:09:32.721946 :C7B60417:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23256 osp_idx=23 osp_ver=278715 osp_pg=0 (spawn #549188) 2025-09-01 22:09:32.721947 :C7B60418:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:09:32.721948 :C7B60419:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=1150224018 2025-09-01 22:09:32.721948 :C7B6041A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:09:32.721948 :C7B6041B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:09:32.721949 :C7B6041C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:09:32.721949 :C7B6041D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:09:32.721949 :C7B6041E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:09:32.722443 :C7B6041F: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-09-01 22:10:02.777879 :C7B60DFF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:10:32.796090 :C7B61828:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:10:32.784 2025-09-01 22:10:32.796092 :C7B61829:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19336 pso_num=25 pso_serial#=243 2025-09-01 22:10:32.796226 :C7B6182A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19336 osp_idx=23 osp_ver=278716 osp_pg=0 (spawn #549191) 2025-09-01 22:10:32.796227 :C7B6182B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:10:32.796227 :C7B6182C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=1150284096 2025-09-01 22:10:32.796228 :C7B6182D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:10:32.796228 :C7B6182E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:10:32.796228 :C7B6182F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:10:32.796229 :C7B61830:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:10:32.796229 :C7B61831:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:10:32.796703 :C7B61832: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-09-01 22:11:02.881225 :C7B62202:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:11:32.879563 :C7B62BF7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:11:32.867 2025-09-01 22:11:32.879565 :C7B62BF8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19660 pso_num=25 pso_serial#=244 2025-09-01 22:11:32.879693 :C7B62BF9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19660 osp_idx=23 osp_ver=278717 osp_pg=0 (spawn #549193) 2025-09-01 22:11:32.879693 :C7B62BFA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:11:32.879694 :C7B62BFB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=1150344174 2025-09-01 22:11:32.879694 :C7B62BFC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:11:32.879695 :C7B62BFD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:11:32.879695 :C7B62BFE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:11:32.879696 :C7B62BFF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:11:32.879696 :C7B62C00:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:11:32.880174 :C7B62C01: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-09-01 22:12:02.920162 :C7B6359D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:12:32.937805 :C7B63F9F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:12:32.925 2025-09-01 22:12:32.937808 :C7B63FA0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16120 pso_num=25 pso_serial#=245 2025-09-01 22:12:32.937938 :C7B63FA1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16120 osp_idx=23 osp_ver=278718 osp_pg=0 (spawn #549195) 2025-09-01 22:12:32.937938 :C7B63FA2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:12:32.937939 :C7B63FA3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=1150404237 2025-09-01 22:12:32.937939 :C7B63FA4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:12:32.937939 :C7B63FA5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:12:32.937940 :C7B63FA6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:12:32.937941 :C7B63FA7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:12:32.937941 :C7B63FA8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:12:32.938503 :C7B63FA9: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-09-01 22:13:02.973937 :C7B649C9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:13:33.014804 :C7B653E4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:13:33.003 2025-09-01 22:13:33.014806 :C7B653E5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23192 pso_num=25 pso_serial#=246 2025-09-01 22:13:33.014941 :C7B653E6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23192 osp_idx=23 osp_ver=278719 osp_pg=0 (spawn #549198) 2025-09-01 22:13:33.014942 :C7B653E7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:13:33.014942 :C7B653E8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=1150464315 2025-09-01 22:13:33.014943 :C7B653E9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:13:33.014943 :C7B653EA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:13:33.014944 :C7B653EB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:13:33.014944 :C7B653EC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:13:33.014944 :C7B653ED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:13:33.015417 :C7B653EE: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-09-01 22:14:03.126511 :C7B65DBA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:14:33.113255 :C7B66797:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:14:33.102 2025-09-01 22:14:33.113257 :C7B66798:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21988 pso_num=25 pso_serial#=247 2025-09-01 22:14:33.113391 :C7B66799:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21988 osp_idx=23 osp_ver=278720 osp_pg=0 (spawn #549200) 2025-09-01 22:14:33.113392 :C7B6679A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:14:33.113392 :C7B6679B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=1150524408 2025-09-01 22:14:33.113392 :C7B6679C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:14:33.113393 :C7B6679D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:14:33.113393 :C7B6679E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:14:33.113393 :C7B6679F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:14:33.113394 :C7B667A0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:14:33.113905 :C7B667A1: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-09-01 22:15:03.185424 :C7B6724E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:15:33.192735 :C7B67C42:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:15:33.181 2025-09-01 22:15:33.192737 :C7B67C43:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19552 pso_num=25 pso_serial#=248 2025-09-01 22:15:33.192869 :C7B67C44:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19552 osp_idx=23 osp_ver=278721 osp_pg=0 (spawn #549202) 2025-09-01 22:15:33.192869 :C7B67C45:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:15:33.192870 :C7B67C46:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=1150584487 2025-09-01 22:15:33.192870 :C7B67C47:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:15:33.192871 :C7B67C48:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:15:33.192871 :C7B67C49:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:15:33.192871 :C7B67C4A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:15:33.192872 :C7B67C4B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:15:33.193355 :C7B67C4C: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-09-01 22:16:03.281069 :C7B68625:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:16:33.287900 :C7B6904D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:16:33.277 2025-09-01 22:16:33.287902 :C7B6904E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21196 pso_num=25 pso_serial#=249 2025-09-01 22:16:33.288031 :C7B6904F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21196 osp_idx=23 osp_ver=278722 osp_pg=0 (spawn #549204) 2025-09-01 22:16:33.288031 :C7B69050:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:16:33.288032 :C7B69051:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=1150644580 2025-09-01 22:16:33.288032 :C7B69052:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:16:33.288032 :C7B69053:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:16:33.288033 :C7B69054:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:16:33.288033 :C7B69055:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:16:33.288033 :C7B69056:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:16:33.288513 :C7B69057: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-09-01 22:17:03.348179 :C7B69A4E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:17:33.372877 :C7B6A406:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:17:33.361 2025-09-01 22:17:33.372879 :C7B6A407:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19848 pso_num=25 pso_serial#=250 2025-09-01 22:17:33.372996 :C7B6A408:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19848 osp_idx=23 osp_ver=278723 osp_pg=0 (spawn #549206) 2025-09-01 22:17:33.372997 :C7B6A409:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:17:33.372997 :C7B6A40A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=1150704674 2025-09-01 22:17:33.372998 :C7B6A40B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:17:33.372998 :C7B6A40C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:17:33.372999 :C7B6A40D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:17:33.373000 :C7B6A40E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:17:33.373000 :C7B6A40F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:17:33.373508 :C7B6A410: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-09-01 22:18:03.459618 :C7B6AE55:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-01 22:18:18.454502 :C7B6B38D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-01 22:18:18.443 2025-09-01 22:18:18.454504 :C7B6B38E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22844 pso_num=25 pso_serial#=251 2025-09-01 22:18:18.454632 :C7B6B38F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22844 osp_idx=23 osp_ver=278724 osp_pg=0 (spawn #549208) 2025-09-01 22:18:18.454633 :C7B6B390:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-01 22:18:18.454633 :C7B6B391:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=1150749752 2025-09-01 22:18:18.454633 :C7B6B392:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-01 22:18:18.454634 :C7B6B393:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-01 22:18:18.454634 :C7B6B394:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-01 22:18:18.454634 :C7B6B395:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-01 22:18:18.454635 :C7B6B396:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-01 22:18:18.455111 :C7B6B397: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-09-01 22:18:33.480083 :C7B728BC: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 22844, J001)