Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250513220008\orcl_j004_12420_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:37381M/63366M, Ph+PgF:44649M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 12420, image: ORACLE.EXE (J004) *** 2025-05-13 22:00:08.956 *** SESSION ID:(241.1637) 2025-05-13 22:00:08.956 *** 2025-05-13 22:00:08.956 Process diagnostic dump for ORACLE.EXE (J004), OS id=12420, pid: 30, proc_ser: 30, sid: 241, sess_ser: 1637 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12420 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.341959 sec, exc=0.341959 sec, total=0.341959 sec wait times: max=0.500000 sec, heur=4.848508 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500018 sec, exc=0.500018 sec, total=0.500018 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.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.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.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.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500140 sec, exc=0.500140 sec, total=0.500140 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500164 sec, exc=0.500164 sec, total=0.500164 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.511700 sec, exc=0.511700 sec, total=0.511700 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.494446 sec, exc=0.494446 sec, total=0.494446 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 241 serial 1637 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00:04 - 22:00:08] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-13 22:00:08.956 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 12420, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-12 22:00:04.007322 :96425738:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:04.011033 :96425740:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 22:00:04.011125 :96425743:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-12 22:00:34.127779 :96426611:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-12 22:02:15.246543 :96428773:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 22:02:15.242 2025-05-12 22:02:15.246544 :96428774:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=4660 pso_num=30 pso_serial#=22 2025-05-12 22:02:15.246674 :96428775:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=4660 osp_idx=28 osp_ver=786 osp_pg=0 (spawn #175835) 2025-05-12 22:02:15.246675 :96428776:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 22:02:15.246675 :96428777:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=109 time=63145094 2025-05-12 22:02:15.246676 :96428778:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 22:02:15.246676 :96428779:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 22:02:15.246676 :9642877A:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 22:02:15.246677 :9642877B:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 22:02:15.246677 :9642877C:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 22:02:15.247211 :9642877D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 22:02:15.247254 :96428783:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-12 22:02:15.256306 :9642879B:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-12 23:02:23.468963 :96472209:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 23:02:23.466 2025-05-12 23:02:23.468964 :9647220A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=6556 pso_num=30 pso_serial#=23 2025-05-12 23:02:23.469107 :9647220B:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=6556 osp_idx=28 osp_ver=787 osp_pg=0 (spawn #175974) 2025-05-12 23:02:23.469108 :9647220C:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 23:02:23.469109 :9647220D:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=110 time=66753329 2025-05-12 23:02:23.469109 :9647220E:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 23:02:23.469110 :9647220F:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 23:02:23.469110 :96472210:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 23:02:23.469111 :96472211:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 23:02:23.469112 :96472212:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 23:02:23.469648 :96472213:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-12 23:02:23.469694 :96472219:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-12 23:02:23.478638 :96472231:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-12 23:59:14.429045 :964B7AAB:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 23:59:14.421 2025-05-12 23:59:14.429047 :964B7AAC:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12044 pso_num=30 pso_serial#=24 2025-05-12 23:59:14.429179 :964B7AAD:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=12044 (legacy spawn) 2025-05-12 23:59:14.435638 :964B7AAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 23:59:14.435812 :964B7AB3:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-12 23:59:14.436704 :964B7AB8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-12 23:59:14.447102 :964B7ABB:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 23:59:14.437 2025-05-12 23:59:14.447105 :964B7ABC:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12628 pso_num=30 pso_serial#=25 2025-05-12 23:59:14.447271 :964B7ABD:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=12628 (legacy spawn) 2025-05-12 23:59:14.461456 :964B7ABE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-12 23:59:47.630681 :964B8754:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-12 23:59:51.585096 :964B88BD:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-12 23:59:51.578 2025-05-12 23:59:51.585098 :964B88BE:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13000 pso_num=30 pso_serial#=26 2025-05-12 23:59:51.585237 :964B88BF:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=13000 (legacy spawn) 2025-05-12 23:59:51.599316 :964B88C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:24.644898 :964B93D5:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-13 00:00:33.082882 :964B9930:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-13 00:00:33.077 2025-05-13 00:00:33.082884 :964B9931:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=4212 pso_num=30 pso_serial#=27 2025-05-13 00:00:33.083018 :964B9932:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=4212 osp_idx=28 osp_ver=791 osp_pg=0 (spawn #176104) 2025-05-13 00:00:33.083019 :964B9933:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 00:00:33.083019 :964B9934:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=111 time=70242938 2025-05-13 00:00:33.083020 :964B9935:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 00:00:33.083020 :964B9936:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 00:00:33.083020 :964B9937:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 00:00:33.083021 :964B9938:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 00:00:33.083021 :964B9939:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 00:00:33.083502 :964B993A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.083544 :964B9940:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-13 00:00:33.090806 :964B994E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.091845 :964B9956:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.092497 :964B995E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.093032 :964B9966:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.093479 :964B996E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.095901 :964B9976:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.097064 :964B997E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.146602 :964B9986:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.147496 :964B998E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.147727 :964B9996:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.147936 :964B999E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 00:00:33.149545 :964B99A6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-13 00:02:31.987623 :964BC0D3:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-13 00:02:31.975 2025-05-13 00:02:31.987625 :964BC0D4:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12276 pso_num=30 pso_serial#=28 2025-05-13 00:02:31.987766 :964BC0D5:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12276 osp_idx=28 osp_ver=792 osp_pg=0 (spawn #176111) 2025-05-13 00:02:31.987766 :964BC0D6:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 00:02:31.987767 :964BC0D7:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=112 time=70361844 2025-05-13 00:02:31.987767 :964BC0D8:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 00:02:31.987768 :964BC0D9:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 00:02:31.987768 :964BC0DA:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 00:02:31.987769 :964BC0DB:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 00:02:31.987769 :964BC0DC:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 00:02:31.988268 :964BC0DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-13 00:02:31.988315 :964BC0E3:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-13 00:02:31.992440 :964BC0FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-13 00:02:31.992505 :964BC0FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-13 00:02:31.992770 :964BC0FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-13 00:02:31.992786 :964BC0FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-13 00:02:31.992999 :964BC0FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-13 00:02:31.993013 :964BC100:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-13 00:02:31.993228 :964BC101:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-13 00:02:31.993242 :964BC102:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-13 00:02:31.993453 :964BC103:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-13 00:02:31.993466 :964BC104:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-13 00:02:31.993671 :964BC105:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-13 00:02:31.993684 :964BC106:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-13 00:02:31.996209 :964BC129:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-13 01:02:39.905337 :96505F0A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-13 01:02:39.901 2025-05-13 01:02:39.905338 :96505F0B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13296 pso_num=30 pso_serial#=29 2025-05-13 01:02:39.905473 :96505F0C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=13296 osp_idx=28 osp_ver=793 osp_pg=0 (spawn #176250) 2025-05-13 01:02:39.905474 :96505F0D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 01:02:39.905474 :96505F0E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=113 time=73969766 2025-05-13 01:02:39.905475 :96505F0F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 01:02:39.905475 :96505F10:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 01:02:39.905476 :96505F11:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 01:02:39.905476 :96505F12:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 01:02:39.905476 :96505F13:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 01:02:39.906023 :96505F14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-13 01:02:39.906076 :96505F1A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-13 01:02:39.915438 :96505F32:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-13 22:00:02.159384 :96B136D7:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-13 22:00:02.147 2025-05-13 22:00:02.159386 :96B136D8:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12420 pso_num=30 pso_serial#=30 2025-05-13 22:00:02.159532 :96B136D9:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12420 osp_idx=28 osp_ver=794 osp_pg=0 (spawn #179158) 2025-05-13 22:00:02.159532 :96B136DA:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 22:00:02.159533 :96B136DB:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=114 time=149412016 2025-05-13 22:00:02.159534 :96B136DC:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 22:00:02.159534 :96B136DD:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 22:00:02.159534 :96B136DE:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 22:00:02.159535 :96B136DF:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 22:00:02.159535 :96B136E0:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 22:00:02.160189 :96B136E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.165074 :96B13839:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.165117 :96B1383C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-05-13 22:00:02.170406 :96B13887:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.414626 :96B139DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.437287 :96B139E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.639048 :96B13A16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:02.777457 :96B13A26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.903012 :96B13C1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.904131 :96B13C2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.908912 :96B13C36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.911370 :96B13C3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.913746 :96B13C46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.916091 :96B13C4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.917607 :96B13C56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.939671 :96B13C95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.940334 :96B13C9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.940874 :96B13CA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.941369 :96B13CAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.944292 :96B13CB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.945230 :96B13CBD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:03.946053 :96B13CBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:03.946851 :96B13CBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:03.947279 :96B13CC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:03.947286 :96B13CC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:03.947873 :96B13CC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:03.948363 :96B13CC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:03.948676 :96B13CC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:03.949753 :96B13CC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:03.985043 :96B13CC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.985092 :96B13CC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:03.986086 :96B13CCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:03.986986 :96B13CD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:03.987447 :96B13CD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.008171 :96B13CF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.008227 :96B13CF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:04.009313 :96B13D00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.021287 :96B13D3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:04.021783 :96B13D3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.042060 :96B13D46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.042113 :96B13D49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:04.043048 :96B13D4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.043947 :96B13D57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:04.044530 :96B13D58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.062719 :96B13D60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.062778 :96B13D63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:04.063626 :96B13D69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.064478 :96B13D71:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:04.065042 :96B13D72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.086400 :96B13D82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.086452 :96B13D85:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:04.087387 :96B13D8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.088232 :96B13D93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-13 22:00:04.088673 :96B13D94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.109925 :96B13D9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.110001 :96B13D9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-13 22:00:04.111223 :96B13DA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.111809 :96B13DAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.112248 :96B13DB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.112454 :96B13DBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.112662 :96B13DC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.112855 :96B13DCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-05-13 22:00:04.117357 :96B13DCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-13 22:00:04.117482 :96B13DD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 12420, J004)