Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250809080049\orcl_m002_4152_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:35620M/63366M, Ph+PgF:41074M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 4152, image: ORACLE.EXE (M002) *** 2025-08-09 08:00:49.608 *** SESSION ID:(193.40939) 2025-08-09 08:00:49.608 *** 2025-08-09 08:00:49.608 Process diagnostic dump for ORACLE.EXE (M002), OS id=4152, pid: 24, proc_ser: 122, sid: 193, sess_ser: 40939 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0xe69a, blocks=0x1 wait_id=1842 seq_num=1843 snap_id=1 wait times: snap=0.006226 sec, exc=0.006226 sec, total=0.006226 sec wait times: max=infinite, heur=0.006226 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.000369 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0xe69a, blocks=0x1 wait_id=1842 seq_num=1843 snap_id=1 wait times: snap=0.006296 sec, exc=0.006296 sec, total=0.006296 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000369 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0xe7ea, blocks=0x1 wait_id=1841 seq_num=1842 snap_id=1 wait times: snap=0.006635 sec, exc=0.006635 sec, total=0.006635 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000363 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0xe682, blocks=0x1 wait_id=1840 seq_num=1841 snap_id=1 wait times: snap=0.004039 sec, exc=0.004039 sec, total=0.004039 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000299 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0xe382, blocks=0x1 wait_id=1839 seq_num=1840 snap_id=1 wait times: snap=0.001377 sec, exc=0.001377 sec, total=0.001377 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000369 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0xe3c2, blocks=0x1 wait_id=1838 seq_num=1839 snap_id=1 wait times: snap=0.006192 sec, exc=0.006192 sec, total=0.006192 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000135 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x8552, blocks=0x1 wait_id=1837 seq_num=1838 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.000149 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0xea1a, blocks=0x1 wait_id=1836 seq_num=1837 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.000254 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0xea32, blocks=0x1 wait_id=1835 seq_num=1836 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.000140 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0xe9d2, blocks=0x1 wait_id=1834 seq_num=1835 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.000150 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x80fa, blocks=0x1 wait_id=1833 seq_num=1834 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000159 sec of elapsed time Sampled Session History of session 193 serial 40939 --------------------------------------------------- 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:00:49] waited for 'db file sequential read', seq_num: 1662 p1: 'file#'=0x2 p2: 'block#'=0x844a p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:00:48] idle wait at each sample [session created at: 08:00:48] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:00:49 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-09 08:00:49.608 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 4152, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-09 07:50:55.548069 :BD56FE34:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-09 07:50:55.548295 :BD56FE35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:50:55.548331 :BD56FE38:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-09 07:50:55.552774 :BD56FE3E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:50:55.552837 :BD56FE41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-08-09 07:50:55.552871 :BD56FE42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-08-09 07:50:55.553150 :BD56FE48:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-09 07:50:55.553466 :BD56FE49:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-09 07:50:55.553578 :BD56FE4A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-09 07:50:55.553778 :BD56FE4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:50:55.553798 :BD56FE4E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-09 07:50:55.555413 :BD56FE54:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-09 07:50:55.555638 :BD56FE55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:50:55.555668 :BD56FE58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-09 07:50:55.556533 :BD56FE5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:50:55.556664 :BD56FE67:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 07:51:25.655068 :BD5707C3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:51:55.545501 :BD571145:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:51:55.534 2025-08-09 07:51:55.545503 :BD571146:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20372 pso_num=24 pso_serial#=108 2025-08-09 07:51:55.545625 :BD571147:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20372 osp_idx=22 osp_ver=698053 osp_pg=0 (spawn #470522) 2025-08-09 07:51:55.545625 :BD571148:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:51:55.545626 :BD571149:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=16 time=3406584845 2025-08-09 07:51:55.545627 :BD57114A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:51:55.545627 :BD57114B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:51:55.545628 :BD57114C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:51:55.545628 :BD57114D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:51:55.545628 :BD57114E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:51:55.546109 :BD57114F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:52:25.665153 :BD571AC8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:52:55.661607 :BD572479:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:52:55.650 2025-08-09 07:52:55.661609 :BD57247A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13548 pso_num=24 pso_serial#=109 2025-08-09 07:52:55.661752 :BD57247B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13548 osp_idx=22 osp_ver=698054 osp_pg=0 (spawn #470524) 2025-08-09 07:52:55.661753 :BD57247C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:52:55.661753 :BD57247D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=17 time=3406644954 2025-08-09 07:52:55.661753 :BD57247E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:52:55.661754 :BD57247F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:52:55.661754 :BD572480:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:52:55.661755 :BD572481:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:52:55.661755 :BD572482:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:52:55.662229 :BD572483:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:53:25.767024 :BD572DF8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:53:55.720249 :BD5737A0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:53:55.709 2025-08-09 07:53:55.720252 :BD5737A1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10676 pso_num=24 pso_serial#=110 2025-08-09 07:53:55.720380 :BD5737A2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10676 osp_idx=22 osp_ver=698055 osp_pg=0 (spawn #470526) 2025-08-09 07:53:55.720381 :BD5737A3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:53:55.720381 :BD5737A4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=18 time=3406705017 2025-08-09 07:53:55.720381 :BD5737A5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:53:55.720382 :BD5737A6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:53:55.720382 :BD5737A7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:53:55.720383 :BD5737A8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:53:55.720383 :BD5737A9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:53:55.720873 :BD5737AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:54:25.768406 :BD574195:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:54:55.773836 :BD574B53:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:54:55.762 2025-08-09 07:54:55.773838 :BD574B54:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20144 pso_num=24 pso_serial#=111 2025-08-09 07:54:55.773973 :BD574B55:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20144 osp_idx=22 osp_ver=698056 osp_pg=0 (spawn #470528) 2025-08-09 07:54:55.773973 :BD574B56:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:54:55.773974 :BD574B57:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=19 time=3406765079 2025-08-09 07:54:55.773974 :BD574B58:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:54:55.773974 :BD574B59:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:54:55.773975 :BD574B5A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:54:55.773975 :BD574B5B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:54:55.773975 :BD574B5C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:54:55.774452 :BD574B5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:55:25.874843 :BD575541:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:55:55.861829 :BD575F59:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:55:55.850 2025-08-09 07:55:55.861831 :BD575F5A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17844 pso_num=24 pso_serial#=112 2025-08-09 07:55:55.861944 :BD575F5B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17844 osp_idx=22 osp_ver=698057 osp_pg=0 (spawn #470531) 2025-08-09 07:55:55.861945 :BD575F5C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:55:55.861946 :BD575F5D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=20 time=3406825158 2025-08-09 07:55:55.861946 :BD575F5E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:55:55.861947 :BD575F5F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:55:55.861947 :BD575F60:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:55:55.861947 :BD575F61:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:55:55.861947 :BD575F62:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:55:55.862449 :BD575F63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:56:25.941777 :BD576941:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:56:55.964088 :BD577300:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:56:55.952 2025-08-09 07:56:55.964089 :BD577301:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18460 pso_num=24 pso_serial#=113 2025-08-09 07:56:55.964216 :BD577302:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18460 osp_idx=22 osp_ver=698058 osp_pg=0 (spawn #470533) 2025-08-09 07:56:55.964218 :BD577303:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:56:55.964218 :BD577304:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=21 time=3406885267 2025-08-09 07:56:55.964219 :BD577305:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:56:55.964219 :BD577306:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:56:55.964219 :BD577307:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:56:55.964220 :BD577308:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:56:55.964220 :BD577309:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:56:55.964724 :BD57730A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:57:10.971817 :BD5777EC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 07:57:10.971851*:BD5777ED:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1754697430 eid=16997525 ctx=0x000000077BD98A08 cwh=0x00000000232801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-09 07:57:10.971851*:BD5777EE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F708 pubTabIdxCnt=0 2025-08-09 07:57:10.971851*:BD5777EF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-08-09 07:57:10.971851*:BD5777F0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-08-09 07:57:10.971851*:BD5777F1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F708 xsc->flg4=65792 flags=2 2025-08-09 07:57:10.971851*:BD5777F2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-08-09 07:57:10.972504 :BD5777F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:57:10.977924 :BD5777FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:57:10.978974 :BD577803:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 07:57:10.979117 :BD57780C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 07:57:41.058441 :BD57813A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:57:56.062855 :BD578645:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:57:56.051 2025-08-09 07:57:56.062857 :BD578646:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19940 pso_num=24 pso_serial#=114 2025-08-09 07:57:56.063001 :BD578647:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19940 osp_idx=22 osp_ver=698059 osp_pg=0 (spawn #470535) 2025-08-09 07:57:56.063002 :BD578648:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:57:56.063002 :BD578649:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=22 time=3406945361 2025-08-09 07:57:56.063003 :BD57864A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:57:56.063003 :BD57864B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:57:56.063003 :BD57864C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:57:56.063003 :BD57864D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:57:56.063004 :BD57864E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:57:56.063492 :BD57864F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:58:26.153827 :BD578FC6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:58:56.129530 :BD57991C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:58:56.118 2025-08-09 07:58:56.129532 :BD57991D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18912 pso_num=24 pso_serial#=115 2025-08-09 07:58:56.129645 :BD57991E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18912 osp_idx=22 osp_ver=698060 osp_pg=0 (spawn #470537) 2025-08-09 07:58:56.129646 :BD57991F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:58:56.129646 :BD579920:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=23 time=3407005423 2025-08-09 07:58:56.129647 :BD579921:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:58:56.129647 :BD579922:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:58:56.129647 :BD579923:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:58:56.129648 :BD579924:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:58:56.129648 :BD579925:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:58:56.130127 :BD579926:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 07:59:26.216553 :BD57A2DA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 07:59:56.215665 :BD57ACBA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 07:59:56.204 2025-08-09 07:59:56.215667 :BD57ACBB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21404 pso_num=24 pso_serial#=116 2025-08-09 07:59:56.215805 :BD57ACBC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21404 osp_idx=22 osp_ver=698061 osp_pg=0 (spawn #470539) 2025-08-09 07:59:56.215806 :BD57ACBD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 07:59:56.215807 :BD57ACBE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=3407065517 2025-08-09 07:59:56.215807 :BD57ACBF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 07:59:56.215807 :BD57ACC0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 07:59:56.215808 :BD57ACC1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 07:59:56.215808 :BD57ACC2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 07:59:56.215808 :BD57ACC3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 07:59:56.216290 :BD57ACC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 08:00:26.298301 :BD57B66A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 08:00:45.309287 :BD57F506:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 08:00:45.305 2025-08-09 08:00:45.309291 :BD57F507:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18808 pso_num=24 pso_serial#=117 2025-08-09 08:00:45.309465 :BD57F508:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18808 (legacy spawn) 2025-08-09 08:00:45.315870 :BD57F509:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 08:00:45.316446 :BD57F50F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 08:00:45.317279 :BD57F513:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 08:00:45.325977 :BD57F516:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 08:00:45.321 2025-08-09 08:00:45.325979 :BD57F517:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8372 pso_num=24 pso_serial#=118 2025-08-09 08:00:45.326119 :BD57F518:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8372 (legacy spawn) 2025-08-09 08:00:45.332642 :BD57F519:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 08:00:45.332949 :BD57F522:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 08:00:45.333770 :BD57F523:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 08:00:46.048101 :BD57F64D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 08:00:46.040 2025-08-09 08:00:46.048103 :BD57F64E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21056 pso_num=24 pso_serial#=119 2025-08-09 08:00:46.048240 :BD57F64F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21056 (legacy spawn) 2025-08-09 08:00:46.055128 :BD57F650:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 08:00:46.055705 :BD57F659:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 08:00:46.056638 :BD57F65A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 08:00:46.365503 :BD57F745:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 08:00:46.352 2025-08-09 08:00:46.365505 :BD57F746:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19156 pso_num=24 pso_serial#=120 2025-08-09 08:00:46.365660 :BD57F747:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19156 osp_idx=22 osp_ver=698065 osp_pg=0 (spawn #470543) 2025-08-09 08:00:46.365661 :BD57F748:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 08:00:46.365661 :BD57F749:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=25 time=3407115658 2025-08-09 08:00:46.365661 :BD57F74A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 08:00:46.365661 :BD57F74B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 08:00:46.365662 :BD57F74C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 08:00:46.365662 :BD57F74D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 08:00:46.365662 :BD57F74E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 08:00:46.367077 :BD57F74F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 08:00:46.367125 :BD57F755:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 08:00:46.462452 :BD57F793:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 08:00:47.628511 :BD57F7AC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 08:00:47.618 2025-08-09 08:00:47.628513 :BD57F7AD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20324 pso_num=24 pso_serial#=121 2025-08-09 08:00:47.628706 :BD57F7AE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20324 (legacy spawn) 2025-08-09 08:00:47.636600 :BD57F7AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 08:00:47.636761 :BD57F7B7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 08:00:47.637797 :BD57F7B9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-09 08:00:48.562379 :BD57F814:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-09 08:00:48.561 2025-08-09 08:00:48.562381 :BD57F815:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4152 pso_num=24 pso_serial#=122 2025-08-09 08:00:48.562522 :BD57F816:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4152 osp_idx=22 osp_ver=698067 osp_pg=0 (spawn #470545) 2025-08-09 08:00:48.562522 :BD57F817:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 08:00:48.562523 :BD57F818:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=26 time=3407117861 2025-08-09 08:00:48.562523 :BD57F819:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 08:00:48.562524 :BD57F81A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 08:00:48.562524 :BD57F81B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 08:00:48.562525 :BD57F81C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 08:00:48.562526 :BD57F81D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 08:00:48.563047 :BD57F81E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 08:00:48.563087 :BD57F824:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 4152, M002)