Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823081120\orcl_m002_19316_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:35279M/63366M, Ph+PgF:40403M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 19316, image: ORACLE.EXE (M002) *** 2025-08-23 08:11:20.951 *** SESSION ID:(219.6909) 2025-08-23 08:11:20.951 *** 2025-08-23 08:11:20.951 Process diagnostic dump for ORACLE.EXE (M002), OS id=19316, pid: 27, proc_ser: 127, sid: 219, sess_ser: 6909 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000100 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000125 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x441a, blocks=0x1 wait_id=1491 seq_num=1492 snap_id=1 wait times: snap=0.000078 sec, exc=0.000078 sec, total=0.000078 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000611 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x4312, blocks=0x1 wait_id=1490 seq_num=1491 snap_id=1 wait times: snap=0.002879 sec, exc=0.002879 sec, total=0.002879 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000134 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x4302, blocks=0x1 wait_id=1489 seq_num=1490 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#=0x42f2, blocks=0x1 wait_id=1488 seq_num=1489 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000142 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x42e2, blocks=0x1 wait_id=1487 seq_num=1488 snap_id=1 wait times: snap=0.000697 sec, exc=0.000697 sec, total=0.000697 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000144 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x42ba, blocks=0x1 wait_id=1486 seq_num=1487 snap_id=1 wait times: snap=0.000102 sec, exc=0.000102 sec, total=0.000102 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000149 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x428a, blocks=0x1 wait_id=1485 seq_num=1486 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.000153 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x427a, blocks=0x1 wait_id=1484 seq_num=1485 snap_id=1 wait times: snap=0.000083 sec, exc=0.000083 sec, total=0.000083 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000396 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x426a, blocks=0x1 wait_id=1483 seq_num=1484 snap_id=1 wait times: snap=0.004911 sec, exc=0.004911 sec, total=0.004911 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000354 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x442a, blocks=0x1 wait_id=1492 seq_num=1493 snap_id=1 wait times: snap=0.000136 sec, exc=0.000136 sec, total=0.000136 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000219 sec of elapsed time Sampled Session History of session 219 serial 6909 --------------------------------------------------- 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:11:20] idle wait at each sample [session created at: 08:11:20] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 08:11:20.951 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 19316, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 08:00:09.885859 :C37C317F: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-08-23 08:00:09.890136 :C37C3187: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-08-23 08:00:09.891197 :C37C318F: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-08-23 08:00:11.111942 :C37C322D: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-08-23 08:00:11.155789 :C37C3235: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-08-23 08:00:11.156100 :C37C323D: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-08-23 08:00:11.156367 :C37C3245: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-08-23 08:00:11.156384 :C37C3248:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 08:00:11.156446 :C37C3254: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-08-23 08:00:11.159281 :C37C3262:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:00:25.798985 :C37C3794:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:00:25.787 2025-08-23 08:00:25.798987 :C37C3795:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20476 pso_num=27 pso_serial#=113 2025-08-23 08:00:25.799114 :C37C3796:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=20476 osp_idx=25 osp_ver=330856 osp_pg=0 (spawn #517207) 2025-08-23 08:00:25.799114 :C37C3797:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:00:25.799115 :C37C3798:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=201 time=321727799 2025-08-23 08:00:25.799115 :C37C3799:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:00:25.799116 :C37C379A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:00:25.799116 :C37C379B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:00:25.799116 :C37C379C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:00:25.799117 :C37C379D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:00:25.799597 :C37C379E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:00:55.859429 :C37C4220:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:01:25.893021 :C37C4C1D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:01:25.881 2025-08-23 08:01:25.893024 :C37C4C1E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21064 pso_num=27 pso_serial#=114 2025-08-23 08:01:25.893152 :C37C4C1F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=21064 osp_idx=25 osp_ver=330857 osp_pg=0 (spawn #517209) 2025-08-23 08:01:25.893152 :C37C4C20:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:01:25.893153 :C37C4C21:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=202 time=321787893 2025-08-23 08:01:25.893153 :C37C4C22:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:01:25.893154 :C37C4C23:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:01:25.893154 :C37C4C24:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:01:25.893154 :C37C4C25:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:01:25.893155 :C37C4C26:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:01:25.893641 :C37C4C27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:01:55.979470 :C37C55F8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:02:25.976505 :C37C5FDF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:02:25.965 2025-08-23 08:02:25.976508 :C37C5FE0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21848 pso_num=27 pso_serial#=115 2025-08-23 08:02:25.976628 :C37C5FE1:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=21848 osp_idx=25 osp_ver=330858 osp_pg=0 (spawn #517211) 2025-08-23 08:02:25.976628 :C37C5FE2:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:02:25.976629 :C37C5FE3:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=203 time=321847987 2025-08-23 08:02:25.976630 :C37C5FE4:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:02:25.976630 :C37C5FE5:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:02:25.976631 :C37C5FE6:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:02:25.976631 :C37C5FE7:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:02:25.976631 :C37C5FE8:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:02:25.977163 :C37C5FE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:02:56.082829 :C37C6A96:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:03:26.034751 :C37C741C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:03:26.023 2025-08-23 08:03:26.034754 :C37C741D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13660 pso_num=27 pso_serial#=116 2025-08-23 08:03:26.034873 :C37C741E:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13660 osp_idx=25 osp_ver=330859 osp_pg=0 (spawn #517213) 2025-08-23 08:03:26.034873 :C37C741F:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:03:26.034874 :C37C7420:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=204 time=321908033 2025-08-23 08:03:26.034874 :C37C7421:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:03:26.034874 :C37C7422:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:03:26.034875 :C37C7423:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:03:26.034875 :C37C7424:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:03:26.034875 :C37C7425:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:03:26.035355 :C37C7426:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:03:56.068380 :C37C7DC6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:04:26.131473 :C37C87A1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:04:26.120 2025-08-23 08:04:26.131475 :C37C87A2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21492 pso_num=27 pso_serial#=117 2025-08-23 08:04:26.131593 :C37C87A3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=21492 osp_idx=25 osp_ver=330860 osp_pg=0 (spawn #517216) 2025-08-23 08:04:26.131594 :C37C87A4:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:04:26.131594 :C37C87A5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=205 time=321968127 2025-08-23 08:04:26.131595 :C37C87A6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:04:26.131595 :C37C87A7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:04:26.131596 :C37C87A8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:04:26.131596 :C37C87A9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:04:26.131596 :C37C87AA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:04:26.132074 :C37C87AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:04:56.226433 :C37C9145:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:05:26.245832 :C37CA07D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:05:26.234 2025-08-23 08:05:26.245834 :C37CA07E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11376 pso_num=27 pso_serial#=118 2025-08-23 08:05:26.245967 :C37CA07F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=11376 osp_idx=25 osp_ver=330861 osp_pg=0 (spawn #517219) 2025-08-23 08:05:26.245968 :C37CA080:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:05:26.245969 :C37CA081:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=206 time=322028252 2025-08-23 08:05:26.245969 :C37CA082:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:05:26.245969 :C37CA083:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:05:26.245970 :C37CA084:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:05:26.245970 :C37CA085:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:05:26.245970 :C37CA086:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:05:26.246470 :C37CA087:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:05:56.320159 :C37CABD3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:06:26.330229 :C37CB54E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:06:26.319 2025-08-23 08:06:26.330231 :C37CB54F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=22272 pso_num=27 pso_serial#=119 2025-08-23 08:06:26.330382 :C37CB550:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=22272 osp_idx=25 osp_ver=330862 osp_pg=0 (spawn #517221) 2025-08-23 08:06:26.330383 :C37CB551:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:06:26.330383 :C37CB552:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=207 time=322088330 2025-08-23 08:06:26.330383 :C37CB553:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:06:26.330384 :C37CB554:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:06:26.330384 :C37CB555:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:06:26.330384 :C37CB556:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:06:26.330385 :C37CB557:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:06:26.330871 :C37CB558:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:06:56.407032 :C37CBEF2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:07:26.433384 :C37CC8E2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:07:26.422 2025-08-23 08:07:26.433386 :C37CC8E3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18800 pso_num=27 pso_serial#=120 2025-08-23 08:07:26.433518 :C37CC8E4:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18800 osp_idx=25 osp_ver=330863 osp_pg=0 (spawn #517223) 2025-08-23 08:07:26.433518 :C37CC8E5:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:07:26.433518 :C37CC8E6:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=208 time=322148440 2025-08-23 08:07:26.433519 :C37CC8E7:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:07:26.433519 :C37CC8E8:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:07:26.433519 :C37CC8E9:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:07:26.433520 :C37CC8EA:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:07:26.433520 :C37CC8EB:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:07:26.433988 :C37CC8EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:07:41.432742 :C37CCDD5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 08:07:41.432773*:C37CCDD6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B5BB58 estart=1755907660 eid=17019749 ctx=0x000000077BD98A08 cwh=0x0000000024DC01B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-23 08:07:41.432773*:C37CCDD7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B5BB58 curCtx=0x0000000024DCF708 pubTabIdxCnt=0 2025-08-23 08:07:41.432773*:C37CCDD8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B5BB58 2025-08-23 08:07:41.432773*:C37CCDD9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B5BB58 2025-08-23 08:07:41.432773*:C37CCDDA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B5BB58 curCtx=0x0000000024DCF708 xsc->flg4=65792 flags=2 2025-08-23 08:07:41.432773*:C37CCDDB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B5BB58 2025-08-23 08:07:41.433381 :C37CCDDC: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-08-23 08:07:41.438750 :C37CCDE4: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-08-23 08:07:41.439701 :C37CCDEC: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-08-23 08:07:41.439828 :C37CCDF5: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-08-23 08:08:11.522782 :C37CD76B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:08:26.502923 :C37CDCA7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:08:26.491 2025-08-23 08:08:26.502925 :C37CDCA8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17416 pso_num=27 pso_serial#=121 2025-08-23 08:08:26.503062 :C37CDCA9:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17416 osp_idx=25 osp_ver=330864 osp_pg=0 (spawn #517225) 2025-08-23 08:08:26.503063 :C37CDCAA:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:08:26.503063 :C37CDCAB:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=209 time=322208502 2025-08-23 08:08:26.503063 :C37CDCAC:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:08:26.503064 :C37CDCAD:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:08:26.503064 :C37CDCAE:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:08:26.503064 :C37CDCAF:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:08:26.503065 :C37CDCB0:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:08:26.503541 :C37CDCB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:08:56.557209 :C37CE64D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:09:26.571480 :C37CEFE7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:09:26.559 2025-08-23 08:09:26.571482 :C37CEFE8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21708 pso_num=27 pso_serial#=122 2025-08-23 08:09:26.571603 :C37CEFE9:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=21708 osp_idx=25 osp_ver=330865 osp_pg=0 (spawn #517227) 2025-08-23 08:09:26.571604 :C37CEFEA:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:09:26.571605 :C37CEFEB:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=210 time=322268580 2025-08-23 08:09:26.571605 :C37CEFEC:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:09:26.571606 :C37CEFED:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:09:26.571606 :C37CEFEE:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:09:26.571606 :C37CEFEF:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:09:26.571607 :C37CEFF0:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:09:26.572204 :C37CEFF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:09:56.633050 :C37CF9D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:10:26.641828 :C37D03BE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:10:26.631 2025-08-23 08:10:26.641830 :C37D03BF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21848 pso_num=27 pso_serial#=123 2025-08-23 08:10:26.641986 :C37D03C0:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=21848 osp_idx=25 osp_ver=330866 osp_pg=0 (spawn #517230) 2025-08-23 08:10:26.641987 :C37D03C1:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:10:26.641988 :C37D03C2:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=211 time=322328643 2025-08-23 08:10:26.641988 :C37D03C3:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:10:26.641988 :C37D03C4:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:10:26.641989 :C37D03C5:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:10:26.641989 :C37D03C6:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:10:26.641990 :C37D03C7:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:10:26.642533 :C37D03C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 08:10:56.747913 :C37D0DB5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:11:18.045487 :C37D4E27:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:11:18.045 2025-08-23 08:11:18.045489 :C37D4E28:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=22112 pso_num=27 pso_serial#=124 2025-08-23 08:11:18.045637 :C37D4E29:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=22112 osp_idx=25 osp_ver=330867 osp_pg=0 (spawn #517232) 2025-08-23 08:11:18.045638 :C37D4E2A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:11:18.045638 :C37D4E2B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=212 time=322380049 2025-08-23 08:11:18.045639 :C37D4E2C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:11:18.045639 :C37D4E2D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:11:18.045639 :C37D4E2E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:11:18.045640 :C37D4E2F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:11:18.045640 :C37D4E30:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:11:18.047537 :C37D4E31: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-08-23 08:11:18.047582 :C37D4E37: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-08-23 08:11:18.163295 :C37D5219:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:11:19.800651 :C37D527A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:11:19.796 2025-08-23 08:11:19.800653 :C37D527B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20116 pso_num=27 pso_serial#=125 2025-08-23 08:11:19.800793 :C37D527C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20116 (legacy spawn) 2025-08-23 08:11:19.807740 :C37D527D: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-08-23 08:11:19.807918 :C37D5286: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-08-23 08:11:19.808806 :C37D5287:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:11:19.818329 :C37D528A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:11:19.811 2025-08-23 08:11:19.818331 :C37D528B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=22116 pso_num=27 pso_serial#=126 2025-08-23 08:11:19.818471 :C37D528C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=22116 (legacy spawn) 2025-08-23 08:11:19.825329 :C37D528D: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-08-23 08:11:19.825505 :C37D5296: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-08-23 08:11:19.826387 :C37D5297:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-23 08:11:20.365886 :C37D52DC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-23 08:11:20.357 2025-08-23 08:11:20.365890 :C37D52DD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19316 pso_num=27 pso_serial#=127 2025-08-23 08:11:20.366019 :C37D52DE:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=19316 osp_idx=25 osp_ver=330870 osp_pg=0 (spawn #517234) 2025-08-23 08:11:20.366019 :C37D52DF:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 08:11:20.366020 :C37D52E0:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=213 time=322382377 2025-08-23 08:11:20.366020 :C37D52E1:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 08:11:20.366020 :C37D52E2:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 08:11:20.366021 :C37D52E3:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 08:11:20.366021 :C37D52E4:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 08:11:20.366021 :C37D52E5:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 08:11:20.366498 :C37D52E6: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-08-23 08:11:20.366543 :C37D52EC: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: 19316, M002)