Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622100839\orcl_j001_12148_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:36387M/63366M, Ph+PgF:42670M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12148, image: ORACLE.EXE (J001) *** 2025-06-22 10:08:39.137 *** SESSION ID:(201.40707) 2025-06-22 10:08:39.137 *** 2025-06-22 10:08:39.137 Process diagnostic dump for ORACLE.EXE (J001), OS id=12148, pid: 25, proc_ser: 187, sid: 201, sess_ser: 40707 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12148 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.316454 sec, exc=0.316454 sec, total=0.316454 sec wait times: max=0.500000 sec, heur=2.322261 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.000008 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.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.000011 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.499973 sec, exc=0.499973 sec, total=0.499973 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 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.510109 sec, exc=0.510109 sec, total=0.510109 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.495678 sec, exc=0.495678 sec, total=0.495678 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 40707 --------------------------------------------------- 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, 10:08:36 - 10:08:39] idle wait at each sample [session created at: 10:08:36] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-22 10:08:39.137 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12148, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-22 10:00:56.652676 :A80E916A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:00:56.642 2025-06-22 10:00:56.652678 :A80E916B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18220 pso_num=25 pso_serial#=178 2025-06-22 10:00:56.652824 :A80E916C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18220 osp_idx=23 osp_ver=165785 osp_pg=0 (spawn #310842) 2025-06-22 10:00:56.652824 :A80E916D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:00:56.652825 :A80E916E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=3562199844 2025-06-22 10:00:56.652825 :A80E916F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:00:56.652825 :A80E9170:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:00:56.652826 :A80E9171:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:00:56.652826 :A80E9172:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:00:56.652826 :A80E9173:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:00:56.653313 :A80E9174: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-06-22 10:01:26.762196 :A80E9BB9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:01:56.741145 :A80EA59C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:01:56.730 2025-06-22 10:01:56.741147 :A80EA59D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17836 pso_num=25 pso_serial#=179 2025-06-22 10:01:56.741334 :A80EA59E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17836 osp_idx=23 osp_ver=165786 osp_pg=0 (spawn #310844) 2025-06-22 10:01:56.741335 :A80EA59F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:01:56.741335 :A80EA5A0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=3562259922 2025-06-22 10:01:56.741336 :A80EA5A1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:01:56.741336 :A80EA5A2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:01:56.741336 :A80EA5A3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:01:56.741337 :A80EA5A4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:01:56.741337 :A80EA5A5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:01:56.741812 :A80EA5A6: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-06-22 10:02:26.836422 :A80EAF21:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:02:56.850653 :A80EB8F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:02:56.840 2025-06-22 10:02:56.850655 :A80EB8F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11336 pso_num=25 pso_serial#=180 2025-06-22 10:02:56.850792 :A80EB8F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11336 osp_idx=23 osp_ver=165787 osp_pg=0 (spawn #310846) 2025-06-22 10:02:56.850793 :A80EB8F9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:02:56.850794 :A80EB8FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=3562320032 2025-06-22 10:02:56.850794 :A80EB8FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:02:56.850795 :A80EB8FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:02:56.850795 :A80EB8FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:02:56.850795 :A80EB8FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:02:56.850796 :A80EB8FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:02:56.851272 :A80EB900: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-06-22 10:03:26.909059 :A80EC30A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:03:56.920796 :A80ECCFD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:03:56.910 2025-06-22 10:03:56.920798 :A80ECCFE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9092 pso_num=25 pso_serial#=181 2025-06-22 10:03:56.920933 :A80ECCFF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9092 osp_idx=23 osp_ver=165788 osp_pg=0 (spawn #310849) 2025-06-22 10:03:56.920934 :A80ECD00:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:03:56.920934 :A80ECD01:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=3562380110 2025-06-22 10:03:56.920934 :A80ECD02:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:03:56.920934 :A80ECD03:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:03:56.920934 :A80ECD04:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:03:56.920935 :A80ECD05:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:03:56.920935 :A80ECD06:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:03:56.921483 :A80ECD07: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-06-22 10:04:21.965752 :A80ED4E4: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-06-22 10:04:21.965784*:A80ED4E5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1750557861 eid=16921507 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-22 10:04:21.965784*:A80ED4E6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-06-22 10:04:21.965784*:A80ED4E7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-06-22 10:04:21.965784*:A80ED4E8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-06-22 10:04:21.965784*:A80ED4E9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-06-22 10:04:21.965784*:A80ED4EA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-06-22 10:04:21.966363 :A80ED4EB: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-06-22 10:04:21.971827 :A80ED4F3: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-06-22 10:04:21.972814 :A80ED4FB: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-06-22 10:04:21.972946 :A80ED503: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 2025-06-22 10:04:52.049112 :A80EDE43:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:04:57.017893 :A80EE305:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:04:57.006 2025-06-22 10:04:57.017895 :A80EE306:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17448 pso_num=25 pso_serial#=182 2025-06-22 10:04:57.018032 :A80EE307:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17448 osp_idx=23 osp_ver=165789 osp_pg=0 (spawn #310851) 2025-06-22 10:04:57.018034 :A80EE308:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:04:57.018034 :A80EE309:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=3562440204 2025-06-22 10:04:57.018035 :A80EE30A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:04:57.018036 :A80EE30B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:04:57.018036 :A80EE30C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:04:57.018037 :A80EE30D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:04:57.018037 :A80EE30E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:04:57.018511 :A80EE30F: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-06-22 10:05:27.086157 :A80EED92:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:05:57.106126 :A80EFCA4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:05:57.094 2025-06-22 10:05:57.106128 :A80EFCA5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16960 pso_num=25 pso_serial#=183 2025-06-22 10:05:57.106248 :A80EFCA6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16960 osp_idx=23 osp_ver=165790 osp_pg=0 (spawn #310854) 2025-06-22 10:05:57.106249 :A80EFCA7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:05:57.106249 :A80EFCA8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=3562500282 2025-06-22 10:05:57.106249 :A80EFCA9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:05:57.106250 :A80EFCAA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:05:57.106250 :A80EFCAB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:05:57.106250 :A80EFCAC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:05:57.106251 :A80EFCAD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:05:57.106755 :A80EFCAE: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-06-22 10:06:27.193766 :A80F07BD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:06:57.236788 :A80F1178:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:06:57.236 2025-06-22 10:06:57.236790 :A80F1179:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16728 pso_num=25 pso_serial#=184 2025-06-22 10:06:57.236873 :A80F117A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16728 osp_idx=23 osp_ver=165791 osp_pg=0 (spawn #310856) 2025-06-22 10:06:57.236873 :A80F117B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:06:57.236875 :A80F117C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=3562560422 2025-06-22 10:06:57.236875 :A80F117D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:06:57.236875 :A80F117E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:06:57.236875 :A80F117F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:06:57.236875 :A80F1180:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:06:57.236875 :A80F1181:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:06:57.237192 :A80F1182: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-06-22 10:07:27.322905 :A80F1B1F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:07:57.329621 :A80F24A9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:07:57.318 2025-06-22 10:07:57.329623 :A80F24AA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18288 pso_num=25 pso_serial#=185 2025-06-22 10:07:57.329760 :A80F24AB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18288 osp_idx=23 osp_ver=165792 osp_pg=0 (spawn #310858) 2025-06-22 10:07:57.329760 :A80F24AC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:07:57.329761 :A80F24AD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=3562620516 2025-06-22 10:07:57.329761 :A80F24AE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:07:57.329762 :A80F24AF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:07:57.329762 :A80F24B0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:07:57.329762 :A80F24B1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:07:57.329763 :A80F24B2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:07:57.330258 :A80F24B3: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-06-22 10:08:27.394047 :A80F2E9C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:08:34.952641 :A80F315E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:08:34.940 2025-06-22 10:08:34.952642 :A80F315F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16416 pso_num=25 pso_serial#=186 2025-06-22 10:08:34.952777 :A80F3160:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16416 osp_idx=23 osp_ver=165793 osp_pg=0 (spawn #310860) 2025-06-22 10:08:34.952777 :A80F3161:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:08:34.952778 :A80F3162:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=3562658141 2025-06-22 10:08:34.952778 :A80F3163:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:08:34.952778 :A80F3164:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:08:34.952779 :A80F3165:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:08:34.952779 :A80F3166:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:08:34.952779 :A80F3167:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:08:34.953284 :A80F3168:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-22 10:08:34.953328 :A80F316E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-22 10:08:34.957436 :A80F3190:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-22 10:08:34.957489 :A80F3191:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-22 10:08:34.957735 :A80F3192:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-22 10:08:34.957751 :A80F3193:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-22 10:08:34.957966 :A80F3194:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-22 10:08:34.957980 :A80F3195:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-22 10:08:34.958183 :A80F3196:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-22 10:08:34.958195 :A80F3197:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-22 10:08:34.958398 :A80F3198:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-22 10:08:34.958411 :A80F3199:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-22 10:08:34.958701 :A80F319A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-22 10:08:34.958716 :A80F319B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-22 10:08:34.961181 :A80F31CE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 10:08:35.057422 :A80F32E2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 10:08:35.049 2025-06-22 10:08:35.057425 :A80F32E3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12148 pso_num=25 pso_serial#=187 2025-06-22 10:08:35.057631 :A80F32E4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12148 osp_idx=23 osp_ver=165794 osp_pg=0 (spawn #310863) 2025-06-22 10:08:35.057632 :A80F32E5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:08:35.057632 :A80F32E6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=3562658250 2025-06-22 10:08:35.057633 :A80F32E7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:08:35.057634 :A80F32E8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:08:35.057635 :A80F32E9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:08:35.057635 :A80F32EA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:08:35.057636 :A80F32EB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:08:35.058154 :A80F32F5: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-06-22 10:08:35.159392 :A80F33AC: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-06-22 10:08:35.164589 :A80F33C5: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-06-22 10:08:35.164627 :A80F33C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-22 10:08:35.169523 :A80F33D8: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-06-22 10:08:35.319857 :A80F33E0: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-06-22 10:08:35.453514 :A80F33E8: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-06-22 10:08:35.587300 :A80F33F0: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-06-22 10:08:36.714191 :A80F3460: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-06-22 10:08:36.715237 :A80F3468: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-06-22 10:08:36.720651 :A80F3470: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-06-22 10:08:36.736552 :A80F3478: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-06-22 10:08:36.737290 :A80F3480: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-06-22 10:08:36.737864 :A80F3488: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-06-22 10:08:36.738378 :A80F3490: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-06-22 10:08:36.741168 :A80F3498: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-06-22 10:08:36.742078 :A80F34A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-22 10:08:36.742877 :A80F34A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-22 10:08:36.743751 :A80F34A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-22 10:08:36.744205 :A80F34A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-22 10:08:36.744212 :A80F34A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-22 10:08:36.744884 :A80F34A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-22 10:08:36.745393 :A80F34A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-22 10:08:36.745722 :A80F34A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-22 10:08:36.746789 :A80F34A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-22 10:08:36.787269 :A80F34B3: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-06-22 10:08:36.787325 :A80F34B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-22 10:08:36.788318 :A80F34BC: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-06-22 10:08:36.789313 :A80F34C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-06-22 10:08:36.789782 :A80F34C5: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-06-22 10:08:36.809757 :A80F3504: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-06-22 10:08:36.809814 :A80F3507:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-06-22 10:08:36.810799 :A80F350D: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-06-22 10:08:36.811290 :A80F3515: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-06-22 10:08:36.811664 :A80F351D: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-06-22 10:08:36.811850 :A80F3525: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-06-22 10:08:36.811993 :A80F352D: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-06-22 10:08:36.812146 :A80F3535:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-22 10:08:36.816263 :A80F3536: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-06-22 10:08:36.816355 :A80F3539: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-06-22 10:08:36.816420 :A80F3546: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: 12148, J001)