Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250530081630\orcl_j001_12936_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:37244M/63366M, Ph+PgF:44158M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12936, image: ORACLE.EXE (J001) *** 2025-05-30 08:16:30.744 *** 2025-05-30 08:16:30.744 Process diagnostic dump for ORACLE.EXE (J001), OS id=12936, pid: 25, proc_ser: 42, sid: ------------------------------------------------------------------------------- Skipping session state dump because target process has no session. ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-30 08:16:30.744 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12936, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-30 08:01:58.834565 :9DEA1CB2: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-30 08:01:58.834565 :9DEA1CB3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=1567879594 2025-05-30 08:01:58.834565 :9DEA1CB4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:01:58.834566 :9DEA1CB5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:01:58.834566 :9DEA1CB6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:01:58.834566 :9DEA1CB7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:01:58.834567 :9DEA1CB8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:01:58.835067 :9DEA1CB9: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-30 08:02:28.903465 :9DEA270F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:02:58.899093 :9DEA30E7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:02:58.888 2025-05-30 08:02:58.899095 :9DEA30E8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10716 pso_num=25 pso_serial#=29 2025-05-30 08:02:58.899230 :9DEA30E9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10716 osp_idx=23 osp_ver=126111 osp_pg=0 (spawn #233872) 2025-05-30 08:02:58.899231 :9DEA30EA: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-30 08:02:58.899232 :9DEA30EB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=1567939657 2025-05-30 08:02:58.899232 :9DEA30EC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:02:58.899232 :9DEA30ED:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:02:58.899233 :9DEA30EE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:02:58.899233 :9DEA30EF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:02:58.899233 :9DEA30F0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:02:58.899701 :9DEA30F1: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-30 08:03:28.958583 :9DEA3AA7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:03:58.985477 :9DEA445B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:03:58.975 2025-05-30 08:03:58.985479 :9DEA445C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14612 pso_num=25 pso_serial#=30 2025-05-30 08:03:58.985613 :9DEA445D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14612 osp_idx=23 osp_ver=126112 osp_pg=0 (spawn #233874) 2025-05-30 08:03:58.985614 :9DEA445E: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-30 08:03:58.985614 :9DEA445F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=1567999750 2025-05-30 08:03:58.985615 :9DEA4460:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:03:58.985615 :9DEA4461:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:03:58.985615 :9DEA4462:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:03:58.985615 :9DEA4463:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:03:58.985616 :9DEA4464:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:03:58.986165 :9DEA4465: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-30 08:04:29.060171 :9DEA4E3B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:04:59.083077 :9DEA5B1F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:04:59.071 2025-05-30 08:04:59.083079 :9DEA5B20:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12920 pso_num=25 pso_serial#=31 2025-05-30 08:04:59.083207 :9DEA5B21:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12920 osp_idx=23 osp_ver=126113 osp_pg=0 (spawn #233877) 2025-05-30 08:04:59.083207 :9DEA5B22: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-30 08:04:59.083208 :9DEA5B23:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=1568059844 2025-05-30 08:04:59.083208 :9DEA5B24:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:04:59.083209 :9DEA5B25:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:04:59.083209 :9DEA5B26:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:04:59.083209 :9DEA5B27:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:04:59.083210 :9DEA5B28:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:04:59.083714 :9DEA5B29: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-30 08:05:29.158363 :9DEA6530:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:05:34.124590 :9DEA6706:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:05:34.113 2025-05-30 08:05:34.124592 :9DEA6707:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12788 pso_num=25 pso_serial#=32 2025-05-30 08:05:34.124719 :9DEA6708:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12788 osp_idx=23 osp_ver=126114 osp_pg=0 (spawn #233879) 2025-05-30 08:05:34.124719 :9DEA6709: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-30 08:05:34.124719 :9DEA670A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=1568094891 2025-05-30 08:05:34.124720 :9DEA670B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:05:34.124720 :9DEA670C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:05:34.124721 :9DEA670D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:05:34.124721 :9DEA670E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:05:34.124722 :9DEA670F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:05:34.125202 :9DEA6710: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-30 08:05:59.152147 :9DEA708D: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-30 08:05:59.152175*:9DEA708E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DCBB58 estart=1748563558 eid=16884884 ctx=0x000000077BD98A08 cwh=0x0000000024D801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-30 08:05:59.152175*:9DEA708F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 pubTabIdxCnt=0 2025-05-30 08:05:59.152175*:9DEA7090:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DCBB58 2025-05-30 08:05:59.152175*:9DEA7091:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DCBB58 2025-05-30 08:05:59.152175*:9DEA7092:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 xsc->flg4=65792 flags=2 2025-05-30 08:05:59.152175*:9DEA7093:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DCBB58 2025-05-30 08:05:59.152800 :9DEA7094: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-30 08:05:59.160467 :9DEA709C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-30 08:05:59.160836 :9DEA709D: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-30 08:05:59.160872 :9DEA70A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-30 08:05:59.161707 :9DEA70A6: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-30 08:05:59.161820 :9DEA70AF: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-05-30 08:06:29.240567 :9DEA7F16:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:06:59.247131 :9DEA88D5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:06:59.236 2025-05-30 08:06:59.247133 :9DEA88D6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8812 pso_num=25 pso_serial#=33 2025-05-30 08:06:59.247269 :9DEA88D7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8812 osp_idx=23 osp_ver=126115 osp_pg=0 (spawn #233881) 2025-05-30 08:06:59.247270 :9DEA88D8: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-30 08:06:59.247270 :9DEA88D9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=1568180016 2025-05-30 08:06:59.247270 :9DEA88DA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:06:59.247270 :9DEA88DB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:06:59.247271 :9DEA88DC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:06:59.247271 :9DEA88DD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:06:59.247271 :9DEA88DE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:06:59.247753 :9DEA88DF: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-30 08:07:29.345376 :9DEA939A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:07:59.349630 :9DEA9DAF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:07:59.339 2025-05-30 08:07:59.349632 :9DEA9DB0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15032 pso_num=25 pso_serial#=34 2025-05-30 08:07:59.349758 :9DEA9DB1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15032 osp_idx=23 osp_ver=126116 osp_pg=0 (spawn #233884) 2025-05-30 08:07:59.349759 :9DEA9DB2: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-30 08:07:59.349759 :9DEA9DB3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=1568240110 2025-05-30 08:07:59.349760 :9DEA9DB4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:07:59.349760 :9DEA9DB5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:07:59.349760 :9DEA9DB6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:07:59.349761 :9DEA9DB7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:07:59.349761 :9DEA9DB8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:07:59.350261 :9DEA9DB9: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-30 08:08:29.453353 :9DEAA784:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:08:59.442224 :9DEAB193:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:08:59.431 2025-05-30 08:08:59.442226 :9DEAB194:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11480 pso_num=25 pso_serial#=35 2025-05-30 08:08:59.442348 :9DEAB195:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11480 osp_idx=23 osp_ver=126117 osp_pg=0 (spawn #233886) 2025-05-30 08:08:59.442349 :9DEAB196: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-30 08:08:59.442349 :9DEAB197:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=1568300204 2025-05-30 08:08:59.442350 :9DEAB198:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:08:59.442350 :9DEAB199:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:08:59.442350 :9DEAB19A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:08:59.442350 :9DEAB19B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:08:59.442351 :9DEAB19C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:08:59.442891 :9DEAB19D: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-30 08:09:29.514626 :9DEABB25:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:09:59.540096 :9DEAC56B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:09:59.528 2025-05-30 08:09:59.540098 :9DEAC56C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15264 pso_num=25 pso_serial#=36 2025-05-30 08:09:59.540241 :9DEAC56D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15264 osp_idx=23 osp_ver=126118 osp_pg=0 (spawn #233889) 2025-05-30 08:09:59.540242 :9DEAC56E: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-30 08:09:59.540242 :9DEAC56F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=1568360297 2025-05-30 08:09:59.540243 :9DEAC570:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:09:59.540243 :9DEAC571:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:09:59.540244 :9DEAC572:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:09:59.540244 :9DEAC573:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:09:59.540244 :9DEAC574:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:09:59.540722 :9DEAC575: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-30 08:10:29.632256 :9DEACF75:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:10:59.647302 :9DEAD9A1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:10:59.636 2025-05-30 08:10:59.647304 :9DEAD9A2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8928 pso_num=25 pso_serial#=37 2025-05-30 08:10:59.647443 :9DEAD9A3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8928 osp_idx=23 osp_ver=126119 osp_pg=0 (spawn #233891) 2025-05-30 08:10:59.647444 :9DEAD9A4: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-30 08:10:59.647444 :9DEAD9A5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=2 time=1568420407 2025-05-30 08:10:59.647445 :9DEAD9A6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:10:59.647445 :9DEAD9A7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:10:59.647445 :9DEAD9A8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:10:59.647446 :9DEAD9A9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:10:59.647446 :9DEAD9AA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:10:59.647928 :9DEAD9AB: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-30 08:11:29.722775 :9DEAE35A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:11:59.753000 :9DEAED16:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:11:59.741 2025-05-30 08:11:59.753002 :9DEAED17:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14716 pso_num=25 pso_serial#=38 2025-05-30 08:11:59.753142 :9DEAED18:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14716 osp_idx=23 osp_ver=126120 osp_pg=0 (spawn #233893) 2025-05-30 08:11:59.753143 :9DEAED19: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-30 08:11:59.753143 :9DEAED1A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=1568480516 2025-05-30 08:11:59.753144 :9DEAED1B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:11:59.753144 :9DEAED1C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:11:59.753145 :9DEAED1D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:11:59.753145 :9DEAED1E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:11:59.753145 :9DEAED1F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:11:59.753632 :9DEAED20: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-30 08:12:29.834507 :9DEAF769:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:12:59.840490 :9DEB014C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:12:59.829 2025-05-30 08:12:59.840492 :9DEB014D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14472 pso_num=25 pso_serial#=39 2025-05-30 08:12:59.840626 :9DEB014E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14472 osp_idx=23 osp_ver=126121 osp_pg=0 (spawn #233895) 2025-05-30 08:12:59.840626 :9DEB014F: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-30 08:12:59.840627 :9DEB0150:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=4 time=1568540610 2025-05-30 08:12:59.840627 :9DEB0151:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:12:59.840627 :9DEB0152:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:12:59.840628 :9DEB0153:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:12:59.840628 :9DEB0154:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:12:59.840628 :9DEB0155:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:12:59.841118 :9DEB0156: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-30 08:13:29.917779 :9DEB0B0C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:13:59.927058 :9DEB14D7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:13:59.916 2025-05-30 08:13:59.927060 :9DEB14D8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11564 pso_num=25 pso_serial#=40 2025-05-30 08:13:59.927192 :9DEB14D9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11564 osp_idx=23 osp_ver=126122 osp_pg=0 (spawn #233897) 2025-05-30 08:13:59.927193 :9DEB14DA: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-30 08:13:59.927193 :9DEB14DB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=5 time=1568600688 2025-05-30 08:13:59.927194 :9DEB14DC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:13:59.927194 :9DEB14DD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:13:59.927195 :9DEB14DE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:13:59.927195 :9DEB14DF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:13:59.927195 :9DEB14E0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:13:59.927673 :9DEB14E1: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-30 08:14:30.010596 :9DEB1EAF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:15:00.004567 :9DEB28EA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:14:59.993 2025-05-30 08:15:00.004569 :9DEB28EB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12548 pso_num=25 pso_serial#=41 2025-05-30 08:15:00.004706 :9DEB28EC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12548 osp_idx=23 osp_ver=126123 osp_pg=0 (spawn #233900) 2025-05-30 08:15:00.004706 :9DEB28ED: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-30 08:15:00.004707 :9DEB28EE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=6 time=1568660766 2025-05-30 08:15:00.004707 :9DEB28EF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:15:00.004707 :9DEB28F0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:15:00.004708 :9DEB28F1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:15:00.004708 :9DEB28F2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:15:00.004708 :9DEB28F3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:15:00.005190 :9DEB28F4: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-30 08:15:30.068179 :9DEB32C0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-30 08:15:35.075352 :9DEB34B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-30 08:15:35.064 2025-05-30 08:15:35.075354 :9DEB34B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12936 pso_num=25 pso_serial#=42 2025-05-30 08:15:35.075483 :9DEB34B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12936 osp_idx=23 osp_ver=126124 osp_pg=0 (spawn #233902) 2025-05-30 08:15:35.075483 :9DEB34B9: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-30 08:15:35.075484 :9DEB34BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=1568695844 2025-05-30 08:15:35.075484 :9DEB34BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 08:15:35.075485 :9DEB34BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 08:15:35.075485 :9DEB34BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 08:15:35.075486 :9DEB34BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 08:15:35.075486 :9DEB34BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 08:15:35.075956 :9DEB34C0: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-30 08:16:00.098265 :9DEB3CC8: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-30 08:16:30.213524 :9DEB80B6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 12936, J001)