Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250714081349\orcl_j000_16044_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:36350M/63366M, Ph+PgF:42326M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 16044, image: ORACLE.EXE (J000) *** 2025-07-14 08:13:49.833 *** SESSION ID:(193.59719) 2025-07-14 08:13:49.833 *** 2025-07-14 08:13:49.833 Process diagnostic dump for ORACLE.EXE (J000), OS id=16044, pid: 24, proc_ser: 92, sid: 193, sess_ser: 59719 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16044 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.017933 sec, exc=0.017933 sec, total=0.017933 sec wait times: max=0.500000 sec, heur=3.529638 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 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=5 seq_num=6 snap_id=1 wait times: snap=0.512731 sec, exc=0.512731 sec, total=0.512731 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500114 sec, exc=0.500114 sec, total=0.500114 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500111 sec, exc=0.500111 sec, total=0.500111 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.498685 sec, exc=0.498685 sec, total=0.498685 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 59719 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 08:13:46 - 08:13:49] idle wait at each sample [session created at: 08:13:46] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-14 08:13:49.833 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 16044, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-14 08:00:27.388305 :B1C997FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:00:27.388525 :B1C99805:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:00:27.390265 :B1C9980D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:00:45.132749 :B1C99E2C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:00:45.121 2025-07-14 08:00:45.132752 :B1C99E2D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3404 pso_num=24 pso_serial#=77 2025-07-14 08:00:45.132896 :B1C99E2E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3404 osp_idx=22 osp_ver=565932 osp_pg=0 (spawn #383892) 2025-07-14 08:00:45.132897 :B1C99E2F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:00:45.132897 :B1C99E30:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=99 time=1160821017 2025-07-14 08:00:45.132898 :B1C99E31:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:00:45.132898 :B1C99E32:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:00:45.132898 :B1C99E33:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:00:45.132898 :B1C99E34:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:00:45.132899 :B1C99E35:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:00:45.133387 :B1C99E36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:01:15.227562 :B1C9AA0A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:01:45.222475 :B1C9B422:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:01:45.211 2025-07-14 08:01:45.222477 :B1C9B423:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4764 pso_num=24 pso_serial#=78 2025-07-14 08:01:45.222625 :B1C9B424:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4764 osp_idx=22 osp_ver=565933 osp_pg=0 (spawn #383895) 2025-07-14 08:01:45.222626 :B1C9B425:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:01:45.222627 :B1C9B426:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=100 time=1160881111 2025-07-14 08:01:45.222627 :B1C9B427:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:01:45.222628 :B1C9B428:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:01:45.222629 :B1C9B429:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:01:45.222629 :B1C9B42A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:01:45.222629 :B1C9B42B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:01:45.223151 :B1C9B42C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:02:10.248130 :B1C9BC5E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-14 08:02:40.335782 :B1C9C655:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:02:45.307876 :B1C9C7E4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:02:45.297 2025-07-14 08:02:45.307878 :B1C9C7E5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15076 pso_num=24 pso_serial#=79 2025-07-14 08:02:45.308010 :B1C9C7E6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15076 osp_idx=22 osp_ver=565934 osp_pg=0 (spawn #383897) 2025-07-14 08:02:45.308011 :B1C9C7E7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:02:45.308012 :B1C9C7E8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=101 time=1160941189 2025-07-14 08:02:45.308012 :B1C9C7E9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:02:45.308013 :B1C9C7EA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:02:45.308013 :B1C9C7EB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:02:45.308014 :B1C9C7EC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:02:45.308014 :B1C9C7ED:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:02:45.308546 :B1C9C7EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:03:15.391035 :B1C9D221:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:03:45.412308 :B1C9DC25:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:03:45.401 2025-07-14 08:03:45.412311 :B1C9DC26:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14688 pso_num=24 pso_serial#=80 2025-07-14 08:03:45.412500 :B1C9DC27:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14688 osp_idx=22 osp_ver=565935 osp_pg=0 (spawn #383900) 2025-07-14 08:03:45.412501 :B1C9DC28:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:03:45.412501 :B1C9DC29:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=102 time=1161001298 2025-07-14 08:03:45.412502 :B1C9DC2A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:03:45.412502 :B1C9DC2B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:03:45.412502 :B1C9DC2C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:03:45.412503 :B1C9DC2D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:03:45.412503 :B1C9DC2E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:03:45.412984 :B1C9DC2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:04:15.477430 :B1C9E65F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:04:45.471498 :B1C9F075:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:04:45.460 2025-07-14 08:04:45.471499 :B1C9F076:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1216 pso_num=24 pso_serial#=81 2025-07-14 08:04:45.471620 :B1C9F077:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=1216 osp_idx=22 osp_ver=565936 osp_pg=0 (spawn #383902) 2025-07-14 08:04:45.471621 :B1C9F078:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:04:45.471621 :B1C9F079:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=103 time=1161061361 2025-07-14 08:04:45.471621 :B1C9F07A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:04:45.471622 :B1C9F07B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:04:45.471622 :B1C9F07C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:04:45.471623 :B1C9F07D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:04:45.471623 :B1C9F07E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:04:45.472128 :B1C9F07F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:05:15.558566 :B1C9FA3B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:05:45.547959 :B1CA0973:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:05:45.537 2025-07-14 08:05:45.547961 :B1CA0974:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7016 pso_num=24 pso_serial#=82 2025-07-14 08:05:45.548095 :B1CA0975:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7016 osp_idx=22 osp_ver=565937 osp_pg=0 (spawn #383904) 2025-07-14 08:05:45.548096 :B1CA0976:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:05:45.548096 :B1CA0977:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=104 time=1161121439 2025-07-14 08:05:45.548096 :B1CA0978:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:05:45.548097 :B1CA0979:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:05:45.548097 :B1CA097A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:05:45.548097 :B1CA097B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:05:45.548098 :B1CA097C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:05:45.548577 :B1CA097D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:06:15.626006 :B1CA14D1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:06:45.641467 :B1CA1EDC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:06:45.630 2025-07-14 08:06:45.641468 :B1CA1EDD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17620 pso_num=24 pso_serial#=83 2025-07-14 08:06:45.641606 :B1CA1EDE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17620 osp_idx=22 osp_ver=565938 osp_pg=0 (spawn #383907) 2025-07-14 08:06:45.641607 :B1CA1EDF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:06:45.641608 :B1CA1EE0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=105 time=1161181533 2025-07-14 08:06:45.641608 :B1CA1EE1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:06:45.641608 :B1CA1EE2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:06:45.641609 :B1CA1EE3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:06:45.641609 :B1CA1EE4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:06:45.641609 :B1CA1EE5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:06:45.642108 :B1CA1EE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:07:15.747919 :B1CA28CA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:07:45.735835 :B1CA35AD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:07:45.724 2025-07-14 08:07:45.735837 :B1CA35AE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17544 pso_num=24 pso_serial#=84 2025-07-14 08:07:45.735956 :B1CA35AF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17544 osp_idx=22 osp_ver=565939 osp_pg=0 (spawn #383909) 2025-07-14 08:07:45.735956 :B1CA35B0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:07:45.735957 :B1CA35B1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=106 time=1161241626 2025-07-14 08:07:45.735957 :B1CA35B2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:07:45.735958 :B1CA35B3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:07:45.735958 :B1CA35B4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:07:45.735958 :B1CA35B5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:07:45.735958 :B1CA35B6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:07:45.736463 :B1CA35B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:08:15.794399 :B1CA3F75:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:08:45.826644 :B1CA496D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:08:45.814 2025-07-14 08:08:45.826646 :B1CA496E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13868 pso_num=24 pso_serial#=85 2025-07-14 08:08:45.826795 :B1CA496F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13868 osp_idx=22 osp_ver=565940 osp_pg=0 (spawn #383911) 2025-07-14 08:08:45.826796 :B1CA4970:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:08:45.826796 :B1CA4971:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=107 time=1161301720 2025-07-14 08:08:45.826797 :B1CA4972:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:08:45.826797 :B1CA4973:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:08:45.826797 :B1CA4974:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:08:45.826798 :B1CA4975:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:08:45.826798 :B1CA4976:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:08:45.827324 :B1CA4977:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:09:15.922807 :B1CA5363:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:09:45.933372 :B1CA5D36:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:09:45.922 2025-07-14 08:09:45.933374 :B1CA5D37:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4280 pso_num=24 pso_serial#=86 2025-07-14 08:09:45.933510 :B1CA5D38:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4280 osp_idx=22 osp_ver=565941 osp_pg=0 (spawn #383913) 2025-07-14 08:09:45.933510 :B1CA5D39:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:09:45.933510 :B1CA5D3A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=108 time=1161361814 2025-07-14 08:09:45.933511 :B1CA5D3B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:09:45.933511 :B1CA5D3C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:09:45.933512 :B1CA5D3D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:09:45.933512 :B1CA5D3E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:09:45.933512 :B1CA5D3F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:09:45.934032 :B1CA5D40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:10:15.994631 :B1CA6708:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:10:46.038022 :B1CA7111:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:10:46.027 2025-07-14 08:10:46.038024 :B1CA7112:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10744 pso_num=24 pso_serial#=87 2025-07-14 08:10:46.038154 :B1CA7113:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10744 osp_idx=22 osp_ver=565942 osp_pg=0 (spawn #383915) 2025-07-14 08:10:46.038155 :B1CA7114:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:10:46.038155 :B1CA7115:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=109 time=1161421923 2025-07-14 08:10:46.038156 :B1CA7116:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:10:46.038156 :B1CA7117:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:10:46.038156 :B1CA7118:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:10:46.038157 :B1CA7119:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:10:46.038157 :B1CA711A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:10:46.038651 :B1CA711B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:11:16.112025 :B1CA7BEB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:11:46.116497 :B1CA85DB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:11:46.104 2025-07-14 08:11:46.116499 :B1CA85DC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17112 pso_num=24 pso_serial#=88 2025-07-14 08:11:46.116615 :B1CA85DD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17112 osp_idx=22 osp_ver=565943 osp_pg=0 (spawn #383917) 2025-07-14 08:11:46.116616 :B1CA85DE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:11:46.116616 :B1CA85DF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=110 time=1161482001 2025-07-14 08:11:46.116617 :B1CA85E0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:11:46.116617 :B1CA85E1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:11:46.116617 :B1CA85E2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:11:46.116617 :B1CA85E3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:11:46.116618 :B1CA85E4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:11:46.117207 :B1CA85E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:12:11.145678 :B1CA8E42:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-14 08:12:41.247962 :B1CA984B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:12:46.213190 :B1CA9A38:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:12:46.202 2025-07-14 08:12:46.213192 :B1CA9A39:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14972 pso_num=24 pso_serial#=89 2025-07-14 08:12:46.213326 :B1CA9A3A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14972 osp_idx=22 osp_ver=565944 osp_pg=0 (spawn #383919) 2025-07-14 08:12:46.213327 :B1CA9A3B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:12:46.213328 :B1CA9A3C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=111 time=1161542095 2025-07-14 08:12:46.213328 :B1CA9A3D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:12:46.213329 :B1CA9A3E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:12:46.213329 :B1CA9A3F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:12:46.213329 :B1CA9A40:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:12:46.213329 :B1CA9A41:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:12:46.213816 :B1CA9A42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:13:16.268709 :B1CAA4BD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:13:44.401600 :B1CAE51A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:13:44.388 2025-07-14 08:13:44.401602 :B1CAE51B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4124 pso_num=24 pso_serial#=90 2025-07-14 08:13:44.401744 :B1CAE51C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4124 (legacy spawn) 2025-07-14 08:13:44.408669 :B1CAE527:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:13:44.408858 :B1CAE531:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-14 08:13:44.409655 :B1CAE533:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:13:44.418394 :B1CAE536:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:13:44.404 2025-07-14 08:13:44.418396 :B1CAE537:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17004 pso_num=24 pso_serial#=91 2025-07-14 08:13:44.418531 :B1CAE538:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17004 (legacy spawn) 2025-07-14 08:13:44.425037 :B1CAE539:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:13:44.425350 :B1CAE542:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-14 08:13:44.426185 :B1CAE543:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-14 08:13:46.294572 :B1CAE828:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-14 08:13:46.289 2025-07-14 08:13:46.294574 :B1CAE829:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16044 pso_num=24 pso_serial#=92 2025-07-14 08:13:46.294701 :B1CAE82A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16044 osp_idx=22 osp_ver=565947 osp_pg=0 (spawn #383922) 2025-07-14 08:13:46.294701 :B1CAE82B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 08:13:46.294702 :B1CAE82C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=112 time=1161602189 2025-07-14 08:13:46.294702 :B1CAE82D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 08:13:46.294702 :B1CAE82E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 08:13:46.294703 :B1CAE82F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 08:13:46.294703 :B1CAE830:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 08:13:46.294703 :B1CAE831:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 08:13:46.295214 :B1CAE832:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 08:13:46.295889 :B1CAE847:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:13:46.303418 :B1CAE865:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-14 08:13:46.303789 :B1CAE866:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:13:46.303826 :B1CAE869:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-14 08:13:46.304699 :B1CAE86F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 08:13:46.304834 :B1CAE878:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 16044, J000)