Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250619220006\orcl_j003_18168_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:36486M/63366M, Ph+PgF:42890M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 18168, image: ORACLE.EXE (J003) *** 2025-06-19 22:00:06.317 *** SESSION ID:(241.2515) 2025-06-19 22:00:06.317 *** 2025-06-19 22:00:06.317 Process diagnostic dump for ORACLE.EXE (J003), OS id=18168, pid: 30, proc_ser: 171, sid: 241, sess_ser: 2515 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18168 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) 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.344673 sec, exc=0.344673 sec, total=0.344673 sec wait times: max=0.500000 sec, heur=2.344808 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 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.510114 sec, exc=0.510114 sec, total=0.510114 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.489994 sec, exc=0.489994 sec, total=0.489994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 241 serial 2515 --------------------------------------------------- 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, 22:00:03 - 22:00:06] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-19 22:00:06.317 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 18168, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-19 15:00:10.846927 :A6D186CA:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=16104 osp_idx=28 osp_ver=1183 osp_pg=0 (spawn #301518) 2025-06-19 15:00:10.846928 :A6D186CB: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-19 15:00:10.846928 :A6D186CC:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=26 time=3320954032 2025-06-19 15:00:10.846929 :A6D186CD:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 15:00:10.846929 :A6D186CE:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 15:00:10.846930 :A6D186CF:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 15:00:10.846930 :A6D186D0:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 15:00:10.846931 :A6D186D1:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 15:00:10.847445 :A6D186D5: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-19 15:00:10.847485 :A6D186DB: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-19 15:00:10.856505 :A6D186FD:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 16:00:18.114328 :A6D63E8A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 16:00:18.112 2025-06-19 16:00:18.114329 :A6D63E8B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18064 pso_num=30 pso_serial#=165 2025-06-19 16:00:18.114466 :A6D63E8E:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18064 osp_idx=28 osp_ver=1184 osp_pg=0 (spawn #301656) 2025-06-19 16:00:18.114466 :A6D63E8F: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-19 16:00:18.114467 :A6D63E90:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=27 time=3324561297 2025-06-19 16:00:18.114467 :A6D63E91:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 16:00:18.114468 :A6D63E92:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 16:00:18.114468 :A6D63E93:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 16:00:18.114468 :A6D63E94:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 16:00:18.114469 :A6D63E95:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 16:00:18.115004 :A6D63E98: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-19 16:00:18.115046 :A6D63E9E: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-19 16:00:18.120517 :A6D63EB7: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-19 16:00:18.148709 :A6D63EE7:db_trace:ksb.c@6610:ksbcic_int(): [10254:30:241] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286490191 8477105) 2025-06-19 16:00:18.148717 :A6D63EE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 16:00:18.148764 :A6D63EF0:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-19 16:00:18.148802 :A6D63F00: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-19 16:00:18.163909 :A6D63F08: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-19 16:00:18.192003 :A6D63F22: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-19 16:00:18.195614 :A6D63F2A: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-19 16:00:18.199276 :A6D63F32: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-19 16:00:18.203401 :A6D63F3A: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-19 16:00:18.204501 :A6D63F42: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-19 16:00:19.424057*:A6D63FA4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B5A1500 estart=1750320018 eid=16779332 ctx=0x0000000777AAE258 cwh=0x000000002B560828 cpu=46875 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-06-19 16:00:19.424057*:A6D63FA5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B5A1500 curCtx=0x0000000028BCF998 pubTabIdxCnt=0 2025-06-19 16:00:19.424057*:A6D63FA6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B5A1500 2025-06-19 16:00:19.424057*:A6D63FA7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B5A1500 2025-06-19 16:00:19.439674*:A6D63FA8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B5A1500 curCtx=0x0000000028BCF998 xsc->flg4=65792 flags=2 2025-06-19 16:00:19.439674*:A6D63FA9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B5A1500 2025-06-19 16:00:19.460068 :A6D63FAA: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-19 16:00:19.494779 :A6D63FB2: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-19 16:00:19.495165 :A6D63FBA: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-19 16:00:19.495471 :A6D63FC2: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-19 16:00:19.495490 :A6D63FC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 16:00:19.495568 :A6D63FD3: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-19 16:00:19.499243 :A6D63FDF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 17:00:25.233849 :A6DAF7F2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 17:00:25.224 2025-06-19 17:00:25.233850 :A6DAF7F3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12024 pso_num=30 pso_serial#=166 2025-06-19 17:00:25.233990 :A6DAF7F7:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=12024 osp_idx=28 osp_ver=1185 osp_pg=0 (spawn #301796) 2025-06-19 17:00:25.233990 :A6DAF7F8: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-19 17:00:25.233991 :A6DAF7F9:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=28 time=3328168422 2025-06-19 17:00:25.233992 :A6DAF7FA:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 17:00:25.233992 :A6DAF7FB:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 17:00:25.233993 :A6DAF7FC:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 17:00:25.233993 :A6DAF7FD:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 17:00:25.233994 :A6DAF7FE:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 17:00:25.234461 :A6DAF7FF: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-19 17:00:25.234497 :A6DAF805: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-19 17:00:25.243431 :A6DAF817:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 18:00:32.605472 :A6DFB158:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 18:00:32.595 2025-06-19 18:00:32.605474 :A6DFB159:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18096 pso_num=30 pso_serial#=167 2025-06-19 18:00:32.605616 :A6DFB15A:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18096 osp_idx=28 osp_ver=1186 osp_pg=0 (spawn #301934) 2025-06-19 18:00:32.605617 :A6DFB15B: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-19 18:00:32.605618 :A6DFB15C:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=29 time=3331775797 2025-06-19 18:00:32.605619 :A6DFB15D:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 18:00:32.605620 :A6DFB15E:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 18:00:32.605621 :A6DFB15F:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 18:00:32.605622 :A6DFB160:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 18:00:32.605622 :A6DFB161:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 18:00:32.606195 :A6DFB162: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-19 18:00:32.606234 :A6DFB168: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-19 18:00:32.615478 :A6DFB17A:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 19:00:40.348135 :A6E46AC9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 19:00:40.338 2025-06-19 19:00:40.348136 :A6E46ACA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18420 pso_num=30 pso_serial#=168 2025-06-19 19:00:40.348273 :A6E46ACB:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18420 osp_idx=28 osp_ver=1187 osp_pg=0 (spawn #302074) 2025-06-19 19:00:40.348273 :A6E46ACC: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-19 19:00:40.348274 :A6E46ACD:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=30 time=3335383532 2025-06-19 19:00:40.348274 :A6E46ACE:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 19:00:40.348274 :A6E46ACF:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 19:00:40.348275 :A6E46AD0:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 19:00:40.348275 :A6E46AD1:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 19:00:40.348275 :A6E46AD2:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 19:00:40.348748 :A6E46AD6: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-19 19:00:40.348795 :A6E46ADC: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-19 19:00:40.357743 :A6E46AEE:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 20:00:47.385140 :A6E924F1:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 20:00:47.374 2025-06-19 20:00:47.385142 :A6E924F2:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15816 pso_num=30 pso_serial#=169 2025-06-19 20:00:47.385270 :A6E924F3:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=15816 osp_idx=28 osp_ver=1188 osp_pg=0 (spawn #302212) 2025-06-19 20:00:47.385271 :A6E924F4: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-19 20:00:47.385271 :A6E924F5:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=31 time=3338990563 2025-06-19 20:00:47.385272 :A6E924F6:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 20:00:47.385272 :A6E924F7:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 20:00:47.385273 :A6E924F8:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 20:00:47.385273 :A6E924F9:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 20:00:47.385273 :A6E924FA:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 20:00:47.385747 :A6E924FE: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-19 20:00:47.385792 :A6E92504: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-19 20:00:47.394899 :A6E92516:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 21:00:54.597729 :A6EDDD82:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 21:00:54.588 2025-06-19 21:00:54.597731 :A6EDDD83:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17740 pso_num=30 pso_serial#=170 2025-06-19 21:00:54.597868 :A6EDDD84:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=17740 osp_idx=28 osp_ver=1189 osp_pg=0 (spawn #302351) 2025-06-19 21:00:54.597869 :A6EDDD85: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-19 21:00:54.597869 :A6EDDD86:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=32 time=3342597782 2025-06-19 21:00:54.597869 :A6EDDD87:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:00:54.597870 :A6EDDD88:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:00:54.597870 :A6EDDD89:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:00:54.597871 :A6EDDD8A:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:00:54.597871 :A6EDDD8B:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:00:54.598368 :A6EDDD8F: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-19 21:00:54.598404 :A6EDDD95: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-19 21:00:54.608001 :A6EDDDA7:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-06-19 22:00:02.052595 :A6F27F50:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 06-19 22:00:02.041 2025-06-19 22:00:02.052597 :A6F27F51:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18168 pso_num=30 pso_serial#=171 2025-06-19 22:00:02.052724 :A6F27F52:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=18168 osp_idx=28 osp_ver=1190 osp_pg=0 (spawn #302488) 2025-06-19 22:00:02.052724 :A6F27F53: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-19 22:00:02.052725 :A6F27F54:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=33 time=3346145235 2025-06-19 22:00:02.052725 :A6F27F55:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 22:00:02.052726 :A6F27F56:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 22:00:02.052726 :A6F27F57:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 22:00:02.052726 :A6F27F58:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 22:00:02.052728 :A6F27F59:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 22:00:02.053352 :A6F27F64: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-19 22:00:02.058961 :A6F280D0: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-19 22:00:02.059007 :A6F280EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-06-19 22:00:02.064520 :A6F282AD: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-19 22:00:02.296839 :A6F285CA: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-19 22:00:02.511344 :A6F285EA: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-19 22:00:02.644905 :A6F285FA: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-19 22:00:03.765824 :A6F288CD: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-19 22:00:03.766850 :A6F288D5: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-19 22:00:03.770813 :A6F288DD: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-19 22:00:03.773776 :A6F288E5: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-19 22:00:03.775339 :A6F288ED: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-19 22:00:03.776749 :A6F288F5: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-19 22:00:03.778969 :A6F288FD: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-19 22:00:03.801124 :A6F28905: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-19 22:00:03.801708 :A6F2890D: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-19 22:00:03.802210 :A6F28915: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-19 22:00:03.802694 :A6F2891D: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-19 22:00:03.805365 :A6F28925: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-19 22:00:03.806297 :A6F2892D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.807259 :A6F2892E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.808185 :A6F2892F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.808680 :A6F28930:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.808686 :A6F28931:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.809317 :A6F28932:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.809817 :A6F28933:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.810101 :A6F28934:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.811184 :A6F28935:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.842860 :A6F28936: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-19 22:00:03.842908 :A6F28939:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.843785 :A6F2893F: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-19 22:00:03.844718 :A6F28947:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.845136 :A6F28948: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-19 22:00:03.865403 :A6F28950: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-19 22:00:03.865455 :A6F28953:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.866317 :A6F28959: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-19 22:00:03.867141 :A6F28961:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.867647 :A6F28962: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-19 22:00:03.885200 :A6F2896A: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-19 22:00:03.885253 :A6F2896D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.886136 :A6F28973: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-19 22:00:03.887087 :A6F2897B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.887574 :A6F2897C: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-19 22:00:03.908798 :A6F28984: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-19 22:00:03.908852 :A6F28987:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.909800 :A6F2898D: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-19 22:00:03.910695 :A6F28995:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.911132 :A6F28996: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-19 22:00:03.931414 :A6F2899E: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-19 22:00:03.931465 :A6F289A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.932367 :A6F289A7: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-19 22:00:03.944444 :A6F289B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-06-19 22:00:03.945074 :A6F289B2: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-19 22:00:03.963156 :A6F289BA: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-19 22:00:03.963213 :A6F289BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-06-19 22:00:03.964075 :A6F289C3: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-19 22:00:03.964604 :A6F289D5: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-19 22:00:03.964993 :A6F289DD: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-19 22:00:03.965177 :A6F289E5: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-19 22:00:03.965344 :A6F289ED: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-19 22:00:03.965545 :A6F289F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-06-19 22:00:03.969639 :A6F289F6: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-19 22:00:03.969724 :A6F289F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 18168, J003)