Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504180206\orcl_j001_2752_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:37574M/63366M, Ph+PgF:45103M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 2752, image: ORACLE.EXE (J001) *** 2025-05-04 18:02:06.099 *** SESSION ID:(201.35493) 2025-05-04 18:02:06.099 *** 2025-05-04 18:02:06.099 Process diagnostic dump for ORACLE.EXE (J001), OS id=2752, pid: 25, proc_ser: 103, sid: 201, sess_ser: 35493 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 2752 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.348867 sec, exc=0.348867 sec, total=0.348867 sec wait times: max=0.500000 sec, heur=2.345569 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=3 seq_num=4 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.496666 sec, exc=0.496666 sec, total=0.496666 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 201 serial 35493 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 18:02:03 - 18:02:06] idle wait at each sample [session created at: 18:02:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-04 18:02:06.099 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 2752, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 17:52:00.804571 :92AAF3EA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=3651897968 2025-05-04 17:52:00.804571 :92AAF3EB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:52:00.804572 :92AAF3EC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:52:00.804572 :92AAF3ED:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:52:00.804572 :92AAF3EE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:52:00.804572 :92AAF3EF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:52:00.805044 :92AAF3F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:52:30.871410 :92AAFDD3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:53:00.884338 :92AB07C1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:53:00.872 2025-05-04 17:53:00.884341 :92AB07C2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10636 pso_num=25 pso_serial#=94 2025-05-04 17:53:00.884466 :92AB07C3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10636 osp_idx=23 osp_ver=74808 osp_pg=0 (spawn #148156) 2025-05-04 17:53:00.884467 :92AB07C4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:53:00.884467 :92AB07C5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=3651958046 2025-05-04 17:53:00.884468 :92AB07C6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:53:00.884469 :92AB07C7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:53:00.884469 :92AB07C8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:53:00.884469 :92AB07C9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:53:00.884470 :92AB07CA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:53:00.884960 :92AB07CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:53:30.993221 :92AB11DB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:54:00.985338 :92AB1BAE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:54:00.974 2025-05-04 17:54:00.985340 :92AB1BAF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12284 pso_num=25 pso_serial#=95 2025-05-04 17:54:00.985475 :92AB1BB0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12284 osp_idx=23 osp_ver=74809 osp_pg=0 (spawn #148158) 2025-05-04 17:54:00.985476 :92AB1BB1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:54:00.985476 :92AB1BB2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=3652018140 2025-05-04 17:54:00.985476 :92AB1BB3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:54:00.985477 :92AB1BB4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:54:00.985477 :92AB1BB5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:54:00.985477 :92AB1BB6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:54:00.985478 :92AB1BB7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:54:00.985980 :92AB1BB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:54:31.102359 :92AB259F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:55:01.061463 :92AB2F82:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:55:01.050 2025-05-04 17:55:01.061465 :92AB2F83:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9176 pso_num=25 pso_serial#=96 2025-05-04 17:55:01.061594 :92AB2F84:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9176 osp_idx=23 osp_ver=74810 osp_pg=0 (spawn #148160) 2025-05-04 17:55:01.061595 :92AB2F85:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:55:01.061595 :92AB2F86:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=3652078218 2025-05-04 17:55:01.061595 :92AB2F87:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:55:01.061596 :92AB2F88:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:55:01.061596 :92AB2F89:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:55:01.061597 :92AB2F8A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:55:01.061597 :92AB2F8B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:55:01.062082 :92AB2F8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:55:31.180247 :92AB39BF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:56:01.168077 :92AB43BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:56:01.157 2025-05-04 17:56:01.168079 :92AB43BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8908 pso_num=25 pso_serial#=97 2025-05-04 17:56:01.168215 :92AB43BE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8908 osp_idx=23 osp_ver=74811 osp_pg=0 (spawn #148163) 2025-05-04 17:56:01.168216 :92AB43BF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:56:01.168217 :92AB43C0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=3652138328 2025-05-04 17:56:01.168217 :92AB43C1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:56:01.168219 :92AB43C2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:56:01.168219 :92AB43C3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:56:01.168219 :92AB43C4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:56:01.168219 :92AB43C5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:56:01.168719 :92AB43C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:56:31.256625 :92AB4D52:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:56:56.235452 :92AB560B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:56:56.224 2025-05-04 17:56:56.235454 :92AB560C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11956 pso_num=25 pso_serial#=98 2025-05-04 17:56:56.235589 :92AB560D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11956 osp_idx=23 osp_ver=74812 osp_pg=0 (spawn #148165) 2025-05-04 17:56:56.235590 :92AB560E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:56:56.235590 :92AB560F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=3652193390 2025-05-04 17:56:56.235590 :92AB5610:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:56:56.235591 :92AB5611:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:56:56.235591 :92AB5612:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:56:56.235591 :92AB5613:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:56:56.235592 :92AB5614:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:56:56.236081 :92AB5615:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:57:01.228077 :92AB57B9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-04 17:57:31.328989 :92AB61B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:58:01.325960 :92AB6B93:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:58:01.315 2025-05-04 17:58:01.325962 :92AB6B94:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11676 pso_num=25 pso_serial#=99 2025-05-04 17:58:01.326097 :92AB6B95:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11676 osp_idx=23 osp_ver=74813 osp_pg=0 (spawn #148167) 2025-05-04 17:58:01.326098 :92AB6B96:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:58:01.326099 :92AB6B97:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=3652258484 2025-05-04 17:58:01.326099 :92AB6B98:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:58:01.326099 :92AB6B99:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:58:01.326100 :92AB6B9A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:58:01.326100 :92AB6B9B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:58:01.326100 :92AB6B9C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:58:01.326593 :92AB6B9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:58:31.404521 :92AB758E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 17:59:01.436788 :92AB7F30:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 17:59:01.425 2025-05-04 17:59:01.436790 :92AB7F31:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8648 pso_num=25 pso_serial#=100 2025-05-04 17:59:01.436922 :92AB7F32:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8648 osp_idx=23 osp_ver=74814 osp_pg=0 (spawn #148169) 2025-05-04 17:59:01.436923 :92AB7F33:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 17:59:01.436923 :92AB7F34:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=3652318593 2025-05-04 17:59:01.436924 :92AB7F35:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 17:59:01.436924 :92AB7F36:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 17:59:01.436924 :92AB7F37:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 17:59:01.436924 :92AB7F38:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 17:59:01.436925 :92AB7F39:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 17:59:01.437411 :92AB7F3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 17:59:31.551530 :92AB893B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 18:00:01.529363 :92AB92B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 18:00:01.518 2025-05-04 18:00:01.529364 :92AB92B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8164 pso_num=25 pso_serial#=101 2025-05-04 18:00:01.529500 :92AB92B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8164 osp_idx=23 osp_ver=74815 osp_pg=0 (spawn #148171) 2025-05-04 18:00:01.529501 :92AB92B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:00:01.529501 :92AB92BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=3652378687 2025-05-04 18:00:01.529501 :92AB92BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:00:01.529502 :92AB92BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:00:01.529502 :92AB92BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:00:01.529502 :92AB92BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:00:01.529503 :92AB92BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:00:01.529987 :92AB92C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 18:00:31.611142 :92AB9F47:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 18:01:01.590876 :92ABA956:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 18:01:01.579 2025-05-04 18:01:01.590878 :92ABA957:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11924 pso_num=25 pso_serial#=102 2025-05-04 18:01:01.591013 :92ABA958:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11924 osp_idx=23 osp_ver=74816 osp_pg=0 (spawn #148175) 2025-05-04 18:01:01.591014 :92ABA959:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:01:01.591014 :92ABA95A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=3652438750 2025-05-04 18:01:01.591014 :92ABA95B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:01:01.591015 :92ABA95C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:01:01.591016 :92ABA95D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:01:01.591016 :92ABA95E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:01:01.591017 :92ABA95F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:01:01.591488 :92ABA960:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 18:01:31.675921 :92ABB358:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-04 18:02:01.663639 :92ABC01C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-04 18:02:01.652 2025-05-04 18:02:01.663642 :92ABC01D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2752 pso_num=25 pso_serial#=103 2025-05-04 18:02:01.663777 :92ABC01E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2752 osp_idx=23 osp_ver=74817 osp_pg=0 (spawn #148177) 2025-05-04 18:02:01.663778 :92ABC01F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:02:01.663778 :92ABC020:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=3652498828 2025-05-04 18:02:01.663779 :92ABC021:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:02:01.663779 :92ABC022:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:02:01.663779 :92ABC023:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:02:01.663780 :92ABC024:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:02:01.663780 :92ABC025:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:02:01.664253 :92ABC026:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 18:02:01.976572 :92ABC20A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-04 18:02:02.075080 :92ABC2BC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-04 18:02:02.080201 :92ABC2D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:02.080241 :92ABC2D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-05-04 18:02:02.083942 :92ABC2E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:02.189241 :92ABC303:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:02.317985 :92ABC30B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:02.450298 :92ABC328:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.601339 :92ABC39A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.602401 :92ABC3A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.604153 :92ABC3AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.605645 :92ABC3B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.607260 :92ABC3BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.611449 :92ABC3C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.626239 :92ABC3CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.626995 :92ABC3D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.627558 :92ABC3DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.628083 :92ABC3E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.630686 :92ABC3EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.631612 :92ABC3F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.632398 :92ABC3F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.633179 :92ABC3F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.633597 :92ABC3F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.633603 :92ABC3F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.634185 :92ABC3F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.634753 :92ABC3F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.635078 :92ABC3F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.636207 :92ABC3FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.663722 :92ABC3FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.663785 :92ABC3FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.664825 :92ABC404:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.665823 :92ABC40C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.666285 :92ABC40D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.684019 :92ABC421:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.684071 :92ABC424:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.684985 :92ABC42A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.685927 :92ABC432:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.686360 :92ABC433:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.705549 :92ABC472:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.705599 :92ABC475:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.706456 :92ABC47B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.707321 :92ABC483:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.707771 :92ABC484:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.727917 :92ABC48C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.727970 :92ABC48F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.728830 :92ABC495:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.729680 :92ABC49D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-04 18:02:03.730202 :92ABC49E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.749200 :92ABC4A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.749253 :92ABC4A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-04 18:02:03.750140 :92ABC4AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.750620 :92ABC4B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.751038 :92ABC4BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.751233 :92ABC4C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.751377 :92ABC4CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.751526 :92ABC4D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-05-04 18:02:03.755960 :92ABC4D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.756051 :92ABC4DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-04 18:02:03.756135 :92ABC4E8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 2752, J001)