Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250615060009\orcl_j004_16608_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:36623M/63366M, Ph+PgF:43050M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 16608, image: ORACLE.EXE (J004) *** 2025-06-15 06:00:09.552 *** SESSION ID:(241.2455) 2025-06-15 06:00:09.552 *** 2025-06-15 06:00:09.552 Process diagnostic dump for ORACLE.EXE (J004), OS id=16608, pid: 30, proc_ser: 145, sid: 241, sess_ser: 2455 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16608 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) 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.327492 sec, exc=0.327492 sec, total=0.327492 sec wait times: max=0.500000 sec, heur=5.831868 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.000009 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.499991 sec, exc=0.499991 sec, total=0.499991 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.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.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.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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.505091 sec, exc=0.505091 sec, total=0.505091 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 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.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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499963 sec, exc=0.499963 sec, total=0.499963 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.500038 sec, exc=0.500038 sec, total=0.500038 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.503108 sec, exc=0.503108 sec, total=0.503108 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.000009 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.500115 sec, exc=0.500115 sec, total=0.500115 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 241 serial 2455 --------------------------------------------------- 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, 06:00:03 - 06:00:09] idle wait at each sample [session created at: 06:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-06-15 06:00:09.568 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 16608, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 06:00:04.019128 :A4721C3F: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-06-14 06:00:04.019214 :A4721C4C: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-06-14 06:00:34.115649 :A4722947:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 13:00:17.166299 :A4931963:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 13:00:17.164 2025-06-14 13:00:17.166300 :A4931964:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16176 pso_num=30 pso_serial#=134 2025-06-14 13:00:17.166425 :A4931965:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=16176 osp_idx=28 osp_ver=1153 osp_pg=0 (spawn #284572) 2025-06-14 13:00:17.166426 :A4931966:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 13:00:17.166426 :A4931967:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=9 time=2881760344 2025-06-14 13:00:17.166427 :A4931968:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 13:00:17.166427 :A4931969:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 13:00:17.166427 :A493196A:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 13:00:17.166428 :A493196B:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 13:00:17.166428 :A493196C:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 13:00:17.167039 :A493196D: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-06-14 13:00:17.167085 :A4931973: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-06-14 13:00:17.175284 :A4931988:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 17:00:46.570250 :A4A62EFD:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 17:00:46.568 2025-06-14 17:00:46.570251 :A4A62EFE:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16740 pso_num=30 pso_serial#=135 2025-06-14 17:00:46.570452 :A4A62EFF:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=16740 osp_idx=28 osp_ver=1154 osp_pg=0 (spawn #285129) 2025-06-14 17:00:46.570453 :A4A62F00:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 17:00:46.570453 :A4A62F01:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=10 time=2896189750 2025-06-14 17:00:46.570454 :A4A62F02:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 17:00:46.570454 :A4A62F03:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 17:00:46.570455 :A4A62F04:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 17:00:46.570455 :A4A62F05:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 17:00:46.570456 :A4A62F06:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 17:00:46.570962 :A4A62F07: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-06-14 17:00:46.571004 :A4A62F0D: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-06-14 17:00:46.578692 :A4A62F22:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 18:00:53.596507 :A4AAF847:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 18:00:53.594 2025-06-14 18:00:53.596508 :A4AAF848:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15948 pso_num=30 pso_serial#=136 2025-06-14 18:00:53.596645 :A4AAF849:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=15948 osp_idx=28 osp_ver=1155 osp_pg=0 (spawn #285267) 2025-06-14 18:00:53.596646 :A4AAF84A:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 18:00:53.596646 :A4AAF84B:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=11 time=2899796766 2025-06-14 18:00:53.596647 :A4AAF84C:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 18:00:53.596647 :A4AAF84D:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 18:00:53.596648 :A4AAF84E:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 18:00:53.596648 :A4AAF84F:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 18:00:53.596649 :A4AAF850:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 18:00:53.597151 :A4AAF851: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-06-14 18:00:53.597214 :A4AAF857: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-06-14 18:00:53.605223 :A4AAF86C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 22:25:32.657103 :A4C016DC:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 22:25:32.648 2025-06-14 22:25:32.657105 :A4C016DD:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12276 pso_num=30 pso_serial#=137 2025-06-14 22:25:32.658940 :A4C016DE:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12276 osp_idx=28 osp_ver=1156 osp_pg=0 (spawn #285884) 2025-06-14 22:25:32.658942 :A4C016DF:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:25:32.658942 :A4C016E0:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=12 time=2915675829 2025-06-14 22:25:32.658943 :A4C016E1:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:25:32.658943 :A4C016E2:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:25:32.658944 :A4C016E3:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:25:32.658944 :A4C016E4:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:25:32.658944 :A4C016E5:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:25:32.661794 :A4C016E6: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-06-14 22:25:32.661845 :A4C016EC: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-06-14 22:25:32.662295 :A4C016ED:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 22:25:33.895977 :A4C01755:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 22:25:33.882 2025-06-14 22:25:33.895979 :A4C01756:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15704 pso_num=30 pso_serial#=138 2025-06-14 22:25:33.896114 :A4C01757:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=15704 osp_idx=28 osp_ver=1157 osp_pg=0 (spawn #285886) 2025-06-14 22:25:33.896115 :A4C01758:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:25:33.896115 :A4C01759:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=13 time=2915677079 2025-06-14 22:25:33.896116 :A4C0175A:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:25:33.896116 :A4C0175B:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:25:33.896116 :A4C0175C:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:25:33.896117 :A4C0175D:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:25:33.896117 :A4C0175E:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:25:33.896625 :A4C0175F: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-06-14 22:25:33.896665 :A4C01765: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-06-14 22:25:33.897067 :A4C01766:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 23:59:05.834563 :A4C7B364:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 23:59:05.823 2025-06-14 23:59:05.834565 :A4C7B365:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13620 pso_num=30 pso_serial#=139 2025-06-14 23:59:05.834727 :A4C7B366:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=13620 (legacy spawn) 2025-06-14 23:59:05.848959 :A4C7B367: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-06-14 23:59:09.266237 :A4C7B464: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-06-14 23:59:09.266415 :A4C7B46B: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-06-14 23:59:09.267583 :A4C7B46E:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 23:59:09.534003 :A4C7B499:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 23:59:09.534 2025-06-14 23:59:09.534005 :A4C7B49A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16408 pso_num=30 pso_serial#=140 2025-06-14 23:59:09.534146 :A4C7B49B:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=16408 (legacy spawn) 2025-06-14 23:59:09.548389 :A4C7B49C: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-06-14 23:59:42.674562 :A4C7C189:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 23:59:42.699214 :A4C7C18C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 23:59:42.687 2025-06-14 23:59:42.699216 :A4C7C18D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=14020 pso_num=30 pso_serial#=141 2025-06-14 23:59:42.699353 :A4C7C18E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=14020 (legacy spawn) 2025-06-14 23:59:42.713992 :A4C7C18F: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-06-14 23:59:46.058453 :A4C7C2A8: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-06-14 23:59:46.058635 :A4C7C2AE: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-06-14 23:59:46.059833 :A4C7C2B2:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-14 23:59:46.312100 :A4C7C2C0:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-14 23:59:46.303 2025-06-14 23:59:46.312102 :A4C7C2C1:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=13828 pso_num=30 pso_serial#=142 2025-06-14 23:59:46.312289 :A4C7C2C2:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=13828 (legacy spawn) 2025-06-14 23:59:46.326349 :A4C7C2C3: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-06-15 00:00:19.503478 :A4C7CE29:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-15 00:00:39.103512 :A4C7D7E4:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-15 00:00:39.093 2025-06-15 00:00:39.103516 :A4C7D7E5:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15968 pso_num=30 pso_serial#=143 2025-06-15 00:00:39.103654 :A4C7D7E6:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=15968 osp_idx=28 osp_ver=1162 osp_pg=0 (spawn #286105) 2025-06-15 00:00:39.103654 :A4C7D7E7:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 00:00:39.103655 :A4C7D7E8:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=14 time=2921382282 2025-06-15 00:00:39.103655 :A4C7D7E9:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 00:00:39.103655 :A4C7D7EA:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 00:00:39.103656 :A4C7D7EB:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 00:00:39.103656 :A4C7D7EC:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 00:00:39.103656 :A4C7D7ED:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 00:00:39.104143 :A4C7D7EE: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-06-15 00:00:39.104191 :A4C7D7F4: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-06-15 00:00:39.111381 :A4C7D802: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-06-15 00:00:39.112423 :A4C7D80A: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-06-15 00:00:39.112980 :A4C7D812: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-06-15 00:00:39.113439 :A4C7D81A: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-06-15 00:00:39.113958 :A4C7D822: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-06-15 00:00:39.116409 :A4C7D82A: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-06-15 00:00:39.117495 :A4C7D832: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-06-15 00:00:39.167086 :A4C7D83A: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-06-15 00:00:39.168071 :A4C7D842: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-06-15 00:00:39.168329 :A4C7D84A: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-06-15 00:00:39.168546 :A4C7D852: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-06-15 00:00:39.170329 :A4C7D85A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-15 00:01:37.810072 :A4C7EBB8:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-15 00:01:37.797 2025-06-15 00:01:37.810074 :A4C7EBB9:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12668 pso_num=30 pso_serial#=144 2025-06-15 00:01:37.810212 :A4C7EBBA:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12668 osp_idx=28 osp_ver=1163 osp_pg=0 (spawn #286109) 2025-06-15 00:01:37.810212 :A4C7EBBB:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 00:01:37.810213 :A4C7EBBC:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=15 time=2921440985 2025-06-15 00:01:37.810213 :A4C7EBBD:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 00:01:37.810214 :A4C7EBBE:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 00:01:37.810214 :A4C7EBBF:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 00:01:37.810214 :A4C7EBC0:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 00:01:37.810215 :A4C7EBC1:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 00:01:37.810736 :A4C7EBC2: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-06-15 00:01:37.810777 :A4C7EBC8: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-06-15 00:01:37.818771 :A4C7EBDA:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-15 06:00:02.045485 :A4E3D684:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-15 06:00:02.044 2025-06-15 06:00:02.045486 :A4E3D685:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=16608 pso_num=30 pso_serial#=145 2025-06-15 06:00:02.045604 :A4E3D68B:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=16608 osp_idx=28 osp_ver=1164 osp_pg=0 (spawn #286940) 2025-06-15 06:00:02.045605 :A4E3D68C:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-15 06:00:02.045605 :A4E3D68D:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=16 time=2942945219 2025-06-15 06:00:02.045606 :A4E3D68E:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 06:00:02.045606 :A4E3D68F:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 06:00:02.045606 :A4E3D690:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 06:00:02.045606 :A4E3D691:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 06:00:02.045607 :A4E3D692:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 06:00:02.046146 :A4E3D693: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-06-15 06:00:02.051234 :A4E3D7E1: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-06-15 06:00:02.051295 :A4E3D7E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-06-15 06:00:02.056818 :A4E3D80E: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-06-15 06:00:02.211790 :A4E3D98A: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-06-15 06:00:02.350837 :A4E3D992: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-06-15 06:00:02.483030 :A4E3D9B2: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-06-15 06:00:03.598961 :A4E3DC0E: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-06-15 06:00:03.600016 :A4E3DC16: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-06-15 06:00:03.605457 :A4E3DC1E: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-06-15 06:00:03.607063 :A4E3DC26: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-06-15 06:00:03.622054 :A4E3DC2E: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-06-15 06:00:03.622712 :A4E3DC36: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-06-15 06:00:03.623250 :A4E3DC3E: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-06-15 06:00:03.623753 :A4E3DC46: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-06-15 06:00:03.626467 :A4E3DC4E: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-06-15 06:00:03.627351 :A4E3DC56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.628127 :A4E3DC57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.628969 :A4E3DC58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.629386 :A4E3DC59:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.629393 :A4E3DC5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.630026 :A4E3DC5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.630534 :A4E3DC5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.630839 :A4E3DC5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.631860 :A4E3DC5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.679708 :A4E3DC5F: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-06-15 06:00:03.679766 :A4E3DC62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.680797 :A4E3DC68: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-06-15 06:00:03.681857 :A4E3DC70:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.682326 :A4E3DC71: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-06-15 06:00:03.704884 :A4E3DCA1: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-06-15 06:00:03.704952 :A4E3DCA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.705987 :A4E3DCAA: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-06-15 06:00:03.706993 :A4E3DCB2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-15 06:00:03.707497 :A4E3DCB3: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-06-15 06:00:03.726157 :A4E3DCBB: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-06-15 06:00:03.726215 :A4E3DCBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-15 06:00:03.727201 :A4E3DCC4: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-06-15 06:00:03.727697 :A4E3DCCC: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-06-15 06:00:03.728070 :A4E3DCD4: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-06-15 06:00:03.728247 :A4E3DCDC: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-06-15 06:00:03.728390 :A4E3DCE4: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-06-15 06:00:03.728529 :A4E3DCEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-06-15 06:00:03.732347 :A4E3DCED: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-06-15 06:00:03.732426 :A4E3DCF0: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-06-15 06:00:03.732512 :A4E3DCFD: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 16608, J004)