Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250618082723\orcl_m002_17072_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:36532M/63366M, Ph+PgF:42921M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 17072, image: ORACLE.EXE (M002) *** 2025-06-18 08:27:23.610 *** SESSION ID:(219.29069) 2025-06-18 08:27:23.610 *** 2025-06-18 08:27:23.610 Process diagnostic dump for ORACLE.EXE (M002), OS id=17072, pid: 27, proc_ser: 193, sid: 219, sess_ser: 29069 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0xe8a, blocks=0x1 wait_id=1305 seq_num=1306 snap_id=1 wait times: snap=0.002445 sec, exc=0.002445 sec, total=0.002445 sec wait times: max=infinite, heur=0.002445 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000127 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0xe6a, blocks=0x1 wait_id=1304 seq_num=1305 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000132 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0xe5a, blocks=0x1 wait_id=1303 seq_num=1304 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000358 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0xe1a, blocks=0x1 wait_id=1302 seq_num=1303 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0xe0a, blocks=0x1 wait_id=1301 seq_num=1302 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000482 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0xdca, blocks=0x1 wait_id=1300 seq_num=1301 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000483 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0xd7a, blocks=0x1 wait_id=1299 seq_num=1300 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000255 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0xd2a, blocks=0x1 wait_id=1298 seq_num=1299 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000147 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0xd1a, blocks=0x1 wait_id=1297 seq_num=1298 snap_id=1 wait times: snap=0.000054 sec, exc=0.000054 sec, total=0.000054 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000245 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0xd0a, blocks=0x1 wait_id=1296 seq_num=1297 snap_id=1 wait times: snap=0.000059 sec, exc=0.000059 sec, total=0.000059 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000877 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0xc82, blocks=0x1 wait_id=1295 seq_num=1296 snap_id=1 wait times: snap=0.000063 sec, exc=0.000063 sec, total=0.000063 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000641 sec of elapsed time Sampled Session History of session 219 serial 29069 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 08:27:23] idle wait at each sample [session created at: 08:27:23] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-18 08:27:23.610 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 17072, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-18 06:56:15.232450 :A63BB6FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 06:56:15.232657 :A63BB6FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 06:56:15.232670 :A63BB6FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 06:56:15.234880 :A63BB6FE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 07:00:15.788241 :A63C087A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 07:00:15.774 2025-06-18 07:00:15.788242 :A63C087B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14720 pso_num=27 pso_serial#=183 2025-06-18 07:00:15.788376 :A63C087C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14720 (legacy spawn) 2025-06-18 07:00:15.795243 :A63C0885:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:15.795424 :A63C088E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 07:00:15.796287 :A63C089D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 07:00:15.806238 :A63C08A8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 07:00:15.806 2025-06-18 07:00:15.806240 :A63C08A9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16436 pso_num=27 pso_serial#=184 2025-06-18 07:00:15.806407 :A63C08AA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16436 (legacy spawn) 2025-06-18 07:00:15.812934 :A63C08B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:15.813184 :A63C08B9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 07:00:15.813971 :A63C08BA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 07:00:17.084580 :A63C0934:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 07:00:17.076 2025-06-18 07:00:17.084582 :A63C0935:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13904 pso_num=27 pso_serial#=185 2025-06-18 07:00:17.084710 :A63C0936:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13904 osp_idx=25 osp_ver=79999 osp_pg=0 (spawn #297074) 2025-06-18 07:00:17.084711 :A63C0937:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 07:00:17.084712 :A63C0938:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=184 time=3205760266 2025-06-18 07:00:17.084712 :A63C0939:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 07:00:17.084712 :A63C093A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 07:00:17.084713 :A63C093B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 07:00:17.084713 :A63C093C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 07:00:17.084713 :A63C093D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 07:00:17.085200 :A63C093E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.085242 :A63C0944:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 07:00:17.092439 :A63C0952:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.093527 :A63C095A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.094075 :A63C0962:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.094531 :A63C096A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.095010 :A63C0972:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.097716 :A63C097A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.098846 :A63C0982:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.148796 :A63C098A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.149685 :A63C0992:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.149916 :A63C099A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.150150 :A63C09A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 07:00:17.151847 :A63C09AA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 07:26:19.105642 :A63E08F1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 07:26:19.093 2025-06-18 07:26:19.105644 :A63E08F2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14096 pso_num=27 pso_serial#=186 2025-06-18 07:26:19.105774 :A63E08F3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14096 osp_idx=25 osp_ver=80000 osp_pg=0 (spawn #297134) 2025-06-18 07:26:19.105774 :A63E08F4:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 07:26:19.105775 :A63E08F5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=185 time=3207322282 2025-06-18 07:26:19.105775 :A63E08F6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 07:26:19.105775 :A63E08F7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 07:26:19.105776 :A63E08F8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 07:26:19.105776 :A63E08F9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 07:26:19.105776 :A63E08FA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 07:26:19.106268 :A63E08FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 07:26:19.106306 :A63E0901:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 07:26:19.110364 :A63E091A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:26:19.110407 :A63E091B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:26:19.110668 :A63E091C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:26:19.110692 :A63E091D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:26:19.110967 :A63E091E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:26:19.110980 :A63E091F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:26:19.111193 :A63E0920:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:26:19.111207 :A63E0921:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:26:19.111420 :A63E0922:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:26:19.111433 :A63E0923:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:26:19.111638 :A63E0924:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:26:19.111651 :A63E0925:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:26:19.113934 :A63E0926:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 07:56:22.634619 :A6404C68:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 07:56:22.622 2025-06-18 07:56:22.634621 :A6404C69:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14664 pso_num=27 pso_serial#=187 2025-06-18 07:56:22.634764 :A6404C6A:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14664 osp_idx=25 osp_ver=80001 osp_pg=0 (spawn #297200) 2025-06-18 07:56:22.634766 :A6404C6B:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 07:56:22.634767 :A6404C6C:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=186 time=3209125813 2025-06-18 07:56:22.634767 :A6404C6D:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 07:56:22.634767 :A6404C6E:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 07:56:22.634768 :A6404C6F:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 07:56:22.634768 :A6404C70:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 07:56:22.634769 :A6404C71:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 07:56:22.635252 :A6404C72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 07:56:22.635291 :A6404C78:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 07:56:22.639381 :A6404C91:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:56:22.639426 :A6404C92:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:56:22.639673 :A6404C93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:56:22.639688 :A6404C94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:56:22.639896 :A6404C95:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:56:22.639909 :A6404C96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:56:22.640115 :A6404C97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:56:22.640135 :A6404C98:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:56:22.640406 :A6404C99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:56:22.640419 :A6404C9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:56:22.640621 :A6404C9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 07:56:22.640634 :A6404C9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 07:56:22.642848 :A6404C9D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 08:00:24.484251 :A6409C00:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 08:00:24.483 2025-06-18 08:00:24.484253 :A6409C01:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12004 pso_num=27 pso_serial#=188 2025-06-18 08:00:24.484389 :A6409C02:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12004 osp_idx=25 osp_ver=80002 osp_pg=0 (spawn #297210) 2025-06-18 08:00:24.484390 :A6409C03:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 08:00:24.484391 :A6409C04:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=187 time=3209367657 2025-06-18 08:00:24.484391 :A6409C05:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:00:24.484391 :A6409C06:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:00:24.484392 :A6409C07:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:00:24.484392 :A6409C08:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:00:24.484393 :A6409C09:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:00:24.484876 :A6409C0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.484920 :A6409C10:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 08:00:24.492875 :A6409C1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.494018 :A6409C26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.494586 :A6409C2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.495043 :A6409C36:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.495487 :A6409C3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.497994 :A6409C46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.499136 :A6409C4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.549024 :A6409C56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.549967 :A6409C5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.550224 :A6409C66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.550446 :A6409C6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:00:24.552290 :A6409C76:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 08:26:26.045593 :A6429972:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 08:26:26.033 2025-06-18 08:26:26.045595 :A6429973:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16748 pso_num=27 pso_serial#=189 2025-06-18 08:26:26.045729 :A6429974:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16748 osp_idx=25 osp_ver=80003 osp_pg=0 (spawn #297271) 2025-06-18 08:26:26.045730 :A6429975:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 08:26:26.045730 :A6429976:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=188 time=3210929235 2025-06-18 08:26:26.045731 :A6429977:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:26:26.045731 :A6429978:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:26:26.045731 :A6429979:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:26:26.045732 :A642997A:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:26:26.045733 :A642997B:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:26:26.046213 :A642997C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 08:26:26.046252 :A6429982:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 08:26:26.050409 :A642999B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 08:26:26.050451 :A642999C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 08:26:26.050702 :A642999D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 08:26:26.050717 :A642999E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 08:26:26.050924 :A642999F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 08:26:26.050937 :A64299A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 08:26:26.051145 :A64299A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 08:26:26.051158 :A64299A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 08:26:26.051360 :A64299A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 08:26:26.051373 :A64299A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 08:26:26.051574 :A64299A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-18 08:26:26.051587 :A64299A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-18 08:26:26.053986 :A64299A7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 08:27:21.073698 :A642E526:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 08:27:21.068 2025-06-18 08:27:21.073701 :A642E527:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15520 pso_num=27 pso_serial#=190 2025-06-18 08:27:21.073859 :A642E528:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15520 osp_idx=25 osp_ver=80004 osp_pg=0 (spawn #297275) 2025-06-18 08:27:21.073860 :A642E529:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 08:27:21.073860 :A642E52A:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=189 time=3210984250 2025-06-18 08:27:21.073861 :A642E52B:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:27:21.073861 :A642E52C:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:27:21.073861 :A642E52D:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:27:21.073862 :A642E52E:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:27:21.073862 :A642E52F:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:27:21.074665 :A642E530:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 08:27:21.074710 :A642E536:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 08:27:21.161727 :A642E568:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 08:27:21.493537 :A642E594:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 08:27:21.490 2025-06-18 08:27:21.493539 :A642E595:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13424 pso_num=27 pso_serial#=191 2025-06-18 08:27:21.493671 :A642E596:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13424 (legacy spawn) 2025-06-18 08:27:21.500142 :A642E597:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:27:21.500308 :A642E5A0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 08:27:21.501077 :A642E5A1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 08:27:21.509728 :A642E5A4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 08:27:21.506 2025-06-18 08:27:21.509730 :A642E5A5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14136 pso_num=27 pso_serial#=192 2025-06-18 08:27:21.509866 :A642E5A6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14136 (legacy spawn) 2025-06-18 08:27:21.516341 :A642E5A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-18 08:27:21.516502 :A642E5B0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-18 08:27:21.517281 :A642E5B1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-18 08:27:23.289274 :A642E67D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-18 08:27:23.281 2025-06-18 08:27:23.289276 :A642E67E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17072 pso_num=27 pso_serial#=193 2025-06-18 08:27:23.289409 :A642E67F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17072 osp_idx=25 osp_ver=80007 osp_pg=0 (spawn #297277) 2025-06-18 08:27:23.289410 :A642E680:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 08:27:23.289410 :A642E681:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=190 time=3210986469 2025-06-18 08:27:23.289410 :A642E682:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 08:27:23.289411 :A642E683:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 08:27:23.289411 :A642E684:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 08:27:23.289411 :A642E685:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 08:27:23.289412 :A642E686:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 08:27:23.289924 :A642E687:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 08:27:23.289977 :A642E68D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 17072, M002)