Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906100320\orcl_j001_15216_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:35014M/63366M, Ph+PgF:39878M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15216, image: ORACLE.EXE (J001) *** 2025-09-06 10:03:20.885 *** SESSION ID:(209.35937) 2025-09-06 10:03:20.885 *** 2025-09-06 10:03:20.885 Process diagnostic dump for ORACLE.EXE (J001), OS id=15216, pid: 26, proc_ser: 185, sid: 209, sess_ser: 35937 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15216 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.408371 sec, exc=0.408371 sec, total=0.408371 sec wait times: max=0.500000 sec, heur=5.921931 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 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=9 seq_num=10 snap_id=1 wait times: snap=0.510096 sec, exc=0.510096 sec, total=0.510096 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510083 sec, exc=0.510083 sec, total=0.510083 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=4 seq_num=5 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.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499972 sec, exc=0.499972 sec, total=0.499972 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time Sampled Session History of session 209 serial 35937 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [6 samples, 10:03:14 - 10:03:20] idle wait at each sample [session created at: 10:03:14] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-06 10:03:20.885 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15216, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 09:58:30.054924 :C9B6D7D8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23564 pso_num=26 pso_serial#=178 2025-09-06 09:58:30.055042 :C9B6D7D9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23564 osp_idx=24 osp_ver=802418 osp_pg=0 (spawn #564156) 2025-09-06 09:58:30.055043 :C9B6D7DA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:58:30.055044 :C9B6D7DB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=2 time=1538361346 2025-09-06 09:58:30.055044 :C9B6D7DC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:58:30.055045 :C9B6D7DD:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:58:30.055045 :C9B6D7DE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:58:30.055045 :C9B6D7DF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:58:30.055046 :C9B6D7E0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:58:30.055532 :C9B6D7E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 09:59:00.145356 :C9B6E19E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 09:59:30.150479 :C9B6EB4D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 09:59:30.138 2025-09-06 09:59:30.150481 :C9B6EB4E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17424 pso_num=26 pso_serial#=179 2025-09-06 09:59:30.150607 :C9B6EB4F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17424 osp_idx=24 osp_ver=802419 osp_pg=0 (spawn #564158) 2025-09-06 09:59:30.150608 :C9B6EB50:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 09:59:30.150609 :C9B6EB51:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=3 time=1538421440 2025-09-06 09:59:30.150609 :C9B6EB52:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 09:59:30.150609 :C9B6EB53:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 09:59:30.150610 :C9B6EB54:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 09:59:30.150610 :C9B6EB55:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 09:59:30.150611 :C9B6EB56:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 09:59:30.151182 :C9B6EB57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:00:00.253458 :C9B6F4BA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 10:00:14.076002 :C9B6F9D9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 10:00:14.071 2025-09-06 10:00:14.076003 :C9B6F9DA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22444 pso_num=26 pso_serial#=180 2025-09-06 10:00:14.076134 :C9B6F9DB:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22444 osp_idx=24 osp_ver=802420 osp_pg=0 (spawn #564160) 2025-09-06 10:00:14.076134 :C9B6F9DC:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 10:00:14.076135 :C9B6F9DD:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=4 time=1538465362 2025-09-06 10:00:14.076135 :C9B6F9DE:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 10:00:14.076135 :C9B6F9DF:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 10:00:14.076136 :C9B6F9E0:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 10:00:14.076136 :C9B6F9E1:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 10:00:14.076136 :C9B6F9E2:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 10:00:14.076628 :C9B6F9E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.076671 :C9B6F9E9:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-06 10:00:14.083889 :C9B6F9F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.084959 :C9B6F9FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.085523 :C9B6FA07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.086027 :C9B6FA0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.086491 :C9B6FA17:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.088981 :C9B6FA1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.090083 :C9B6FA27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.141766 :C9B6FA2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.142643 :C9B6FA37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.142883 :C9B6FA3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.143095 :C9B6FA47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:00:14.144862 :C9B6FA4F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 10:00:30.215518 :C9B6FF5A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 10:00:30.204 2025-09-06 10:00:30.215520 :C9B6FF5B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24044 pso_num=26 pso_serial#=181 2025-09-06 10:00:30.215635 :C9B6FF5C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24044 osp_idx=24 osp_ver=802421 osp_pg=0 (spawn #564162) 2025-09-06 10:00:30.215635 :C9B6FF5D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 10:00:30.215636 :C9B6FF5E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=5 time=1538481518 2025-09-06 10:00:30.215637 :C9B6FF5F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 10:00:30.215638 :C9B6FF60:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 10:00:30.215638 :C9B6FF61:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 10:00:30.215638 :C9B6FF62:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 10:00:30.215638 :C9B6FF63:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 10:00:30.216111 :C9B6FF64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:01:00.282218 :C9B709C5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 10:01:30.300631 :C9B71359:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 10:01:30.289 2025-09-06 10:01:30.300633 :C9B7135A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23424 pso_num=26 pso_serial#=182 2025-09-06 10:01:30.300767 :C9B7135B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23424 osp_idx=24 osp_ver=802422 osp_pg=0 (spawn #564164) 2025-09-06 10:01:30.300768 :C9B7135C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 10:01:30.300769 :C9B7135D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=6 time=1538541596 2025-09-06 10:01:30.300769 :C9B7135E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 10:01:30.300769 :C9B7135F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 10:01:30.300770 :C9B71360:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 10:01:30.300770 :C9B71361:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 10:01:30.300770 :C9B71362:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 10:01:30.301248 :C9B71363:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:02:00.351038 :C9B71D01:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 10:02:30.400170 :C9B726A0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 10:02:30.388 2025-09-06 10:02:30.400173 :C9B726A1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22620 pso_num=26 pso_serial#=183 2025-09-06 10:02:30.400287 :C9B726A2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22620 osp_idx=24 osp_ver=802423 osp_pg=0 (spawn #564166) 2025-09-06 10:02:30.400288 :C9B726A3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 10:02:30.400288 :C9B726A4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=7 time=1538601690 2025-09-06 10:02:30.400289 :C9B726A5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 10:02:30.400289 :C9B726A6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 10:02:30.400290 :C9B726A7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 10:02:30.400290 :C9B726A8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 10:02:30.400290 :C9B726A9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 10:02:30.400781 :C9B726AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:03:00.494971 :C9B730FE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 10:03:13.045598 :C9B7356E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 10:03:13.032 2025-09-06 10:03:13.045600 :C9B7356F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22720 pso_num=26 pso_serial#=184 2025-09-06 10:03:13.045748 :C9B73570:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22720 osp_idx=24 osp_ver=802424 osp_pg=0 (spawn #564168) 2025-09-06 10:03:13.045749 :C9B73571:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 10:03:13.045750 :C9B73572:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=8 time=1538644346 2025-09-06 10:03:13.045750 :C9B73573:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 10:03:13.045750 :C9B73574:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 10:03:13.045751 :C9B73575:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 10:03:13.045752 :C9B73576:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 10:03:13.045752 :C9B73577:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 10:03:13.046260 :C9B73578:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:03:13.046302 :C9B7357E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-06 10:03:13.050609 :C9B73595:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-09-06 10:03:13.050662 :C9B73596:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-09-06 10:03:13.050919 :C9B73597:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-09-06 10:03:13.050936 :C9B73598:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-09-06 10:03:13.051151 :C9B73599:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-09-06 10:03:13.051164 :C9B7359A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-09-06 10:03:13.051371 :C9B7359B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-09-06 10:03:13.051385 :C9B7359C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-09-06 10:03:13.051589 :C9B7359D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-09-06 10:03:13.051602 :C9B7359E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-09-06 10:03:13.051802 :C9B7359F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:kelt.c:517 2025-09-06 10:03:13.051815 :C9B735A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:kelt.c:517 2025-09-06 10:03:13.054556 :C9B735C5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-06 10:03:13.168923 :C9B73705:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-06 10:03:13.157 2025-09-06 10:03:13.168925 :C9B73706:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15216 pso_num=26 pso_serial#=185 2025-09-06 10:03:13.169081 :C9B73707:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15216 osp_idx=24 osp_ver=802425 osp_pg=0 (spawn #564172) 2025-09-06 10:03:13.169082 :C9B73708:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-06 10:03:13.169082 :C9B73709:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=9 time=1538644471 2025-09-06 10:03:13.169083 :C9B7370A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 10:03:13.169083 :C9B7370B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 10:03:13.169084 :C9B7370C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 10:03:13.169085 :C9B7370D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 10:03:13.169085 :C9B7370E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 10:03:13.169681 :C9B73713:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-06 10:03:13.266867 :C9B73A1A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-06 10:03:13.272083 :C9B73A34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:13.272124 :C9B73A37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-09-06 10:03:13.276226 :C9B73A47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:13.383514 :C9B73A4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:13.513192 :C9B73A7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:13.640837 :C9B73A87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.766704 :C9B73ADD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.767706 :C9B73AE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.769385 :C9B73AED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.770962 :C9B73AF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.772465 :C9B73AFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.775880 :C9B73B05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.777379 :C9B73B0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.792601 :C9B73B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.793280 :C9B73B1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.793803 :C9B73B25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.794303 :C9B73B2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.797148 :C9B73B35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.798083 :C9B73B3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.798908 :C9B73B3E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.799770 :C9B73B3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.800220 :C9B73B40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.800227 :C9B73B41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.800870 :C9B73B42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.801383 :C9B73B43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.801717 :C9B73B44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.802872 :C9B73B45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.835416 :C9B73B46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.835462 :C9B73B49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.836402 :C9B73B4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.837276 :C9B73B57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.837800 :C9B73B58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.858434 :C9B73B60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.858489 :C9B73B63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.859374 :C9B73B69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.871271 :C9B73B73:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.871759 :C9B73B74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.892207 :C9B73B86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.892259 :C9B73B89:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.893172 :C9B73B8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.894073 :C9B73B97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.894548 :C9B73B98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.916439 :C9B73BD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.916492 :C9B73BDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.917396 :C9B73BE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.918253 :C9B73BE8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.918748 :C9B73BE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.939420 :C9B73BF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.939470 :C9B73BF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.940381 :C9B73BFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.941227 :C9B73C02:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-09-06 10:03:14.941657 :C9B73C03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.961109 :C9B73C0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.961171 :C9B73C0E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-09-06 10:03:14.962074 :C9B73C14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.962571 :C9B73C1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.962930 :C9B73C24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.963199 :C9B73C2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.963362 :C9B73C34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.963532 :C9B73C3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-09-06 10:03:14.968038 :C9B73C49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.968134 :C9B73C4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-06 10:03:14.968221 :C9B73C59:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 15216, J001)