Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250731082443\orcl_m002_13028_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:35876M/63366M, Ph+PgF:41530M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 13028, image: ORACLE.EXE (M002) *** 2025-07-31 08:24:43.255 *** SESSION ID:(219.28851) 2025-07-31 08:24:43.255 *** 2025-07-31 08:24:43.255 Process diagnostic dump for ORACLE.EXE (M002), OS id=13028, pid: 27, proc_ser: 252, sid: 219, sess_ser: 28851 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x88ea, blocks=0x1 wait_id=1792 seq_num=1793 snap_id=1 wait times: snap=0.003800 sec, exc=0.003800 sec, total=0.003800 sec wait times: max=infinite, heur=0.003800 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.000154 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x88fa, blocks=0x1 wait_id=1791 seq_num=1792 snap_id=1 wait times: snap=0.000148 sec, exc=0.000148 sec, total=0.000148 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000492 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0xe992, blocks=0x1 wait_id=1790 seq_num=1791 snap_id=1 wait times: snap=0.006114 sec, exc=0.006114 sec, total=0.006114 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000197 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x7c5a, blocks=0x1 wait_id=1789 seq_num=1790 snap_id=1 wait times: snap=0.000128 sec, exc=0.000128 sec, total=0.000128 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000238 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x889a, blocks=0x1 wait_id=1788 seq_num=1789 snap_id=1 wait times: snap=0.001103 sec, exc=0.001103 sec, total=0.001103 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000292 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x88ca, blocks=0x1 wait_id=1787 seq_num=1788 snap_id=1 wait times: snap=0.001488 sec, exc=0.001488 sec, total=0.001488 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000356 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x7c3a, blocks=0x1 wait_id=1786 seq_num=1787 snap_id=1 wait times: snap=0.005275 sec, exc=0.005275 sec, total=0.005275 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000348 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x883a, blocks=0x1 wait_id=1785 seq_num=1786 snap_id=1 wait times: snap=0.005575 sec, exc=0.005575 sec, total=0.005575 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000383 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x8d4a, blocks=0x1 wait_id=1784 seq_num=1785 snap_id=1 wait times: snap=0.002365 sec, exc=0.002365 sec, total=0.002365 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000338 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0xe972, blocks=0x1 wait_id=1783 seq_num=1784 snap_id=1 wait times: snap=0.003368 sec, exc=0.003368 sec, total=0.003368 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000322 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x893a, blocks=0x1 wait_id=1782 seq_num=1783 snap_id=1 wait times: snap=0.004273 sec, exc=0.004273 sec, total=0.004273 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000326 sec of elapsed time Sampled Session History of session 219 serial 28851 --------------------------------------------------- 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:24:43] waited for 'db file sequential read', seq_num: 1716 p1: 'file#'=0x2 p2: 'block#'=0x8642 p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:24:42] idle wait at each sample [session created at: 08:24:42] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:24:43 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.015000 sec (max dump time=30.000000 sec) *** 2025-07-31 08:24:43.271 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 13028, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-31 01:58:22.018195 :B93D2033:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15096 (legacy spawn) 2025-07-31 01:58:22.024682 :B93D2034: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-07-31 01:58:22.024851 :B93D203D: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-07-31 01:58:22.025690 :B93D203E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:58:25.290757 :B93D2159:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:58:25.278 2025-07-31 01:58:25.290759 :B93D215A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18880 pso_num=27 pso_serial#=234 2025-07-31 01:58:25.290950 :B93D215B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18880 (legacy spawn) 2025-07-31 01:58:25.297491 :B93D215C: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-07-31 01:58:25.297666 :B93D2165: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-07-31 01:58:25.298494 :B93D2166:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:58:25.307120 :B93D2169:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:58:25.293 2025-07-31 01:58:25.307122 :B93D216A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18852 pso_num=27 pso_serial#=235 2025-07-31 01:58:25.307256 :B93D216B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18852 (legacy spawn) 2025-07-31 01:58:25.313637 :B93D216C: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-07-31 01:58:25.313803 :B93D2175: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-07-31 01:58:25.314497 :B93D2176:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:58:26.160936 :B93D21B6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:58:26.153 2025-07-31 01:58:26.160938 :B93D21B7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16212 pso_num=27 pso_serial#=236 2025-07-31 01:58:26.161131 :B93D21B8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16212 (legacy spawn) 2025-07-31 01:58:26.167542 :B93D21B9: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-07-31 01:58:26.167715 :B93D21BE: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-07-31 01:58:26.168580 :B93D21C3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:58:27.021043 :B93D2200:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:58:27.012 2025-07-31 01:58:27.021044 :B93D2201:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13572 pso_num=27 pso_serial#=237 2025-07-31 01:58:27.021186 :B93D2202:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13572 (legacy spawn) 2025-07-31 01:58:27.027677 :B93D2203: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-07-31 01:58:27.027848 :B93D220C: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-07-31 01:58:27.028705 :B93D220D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:59:01.025729 :B93D2D0D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:59:01.016 2025-07-31 01:59:01.025731 :B93D2D0E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20084 pso_num=27 pso_serial#=238 2025-07-31 01:59:01.025879 :B93D2D0F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20084 (legacy spawn) 2025-07-31 01:59:01.049291 :B93D2D10: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-07-31 01:59:01.049467 :B93D2D19: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-07-31 01:59:01.050325 :B93D2D1A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:59:01.584683 :B93D2D40:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:59:01.572 2025-07-31 01:59:01.584686 :B93D2D41:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20324 pso_num=27 pso_serial#=239 2025-07-31 01:59:01.584825 :B93D2D42:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20324 (legacy spawn) 2025-07-31 01:59:01.591251 :B93D2D43: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-07-31 01:59:01.591414 :B93D2D4C: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-07-31 01:59:01.592202 :B93D2D4D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:59:01.609927 :B93D2D50:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:59:01.603 2025-07-31 01:59:01.609929 :B93D2D51:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19908 pso_num=27 pso_serial#=240 2025-07-31 01:59:01.610069 :B93D2D52:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19908 (legacy spawn) 2025-07-31 01:59:01.616613 :B93D2D53: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-07-31 01:59:01.616779 :B93D2D5C: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-07-31 01:59:01.617600 :B93D2D5D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 01:59:28.312820 :B93D358A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 01:59:28.300 2025-07-31 01:59:28.312822 :B93D358B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20168 pso_num=27 pso_serial#=241 2025-07-31 01:59:28.312962 :B93D358C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20168 (legacy spawn) 2025-07-31 01:59:28.319438 :B93D358D: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-07-31 01:59:28.319613 :B93D3595: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-07-31 01:59:28.320419 :B93D3597:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 02:00:23.638606 :B93D49A1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 02:00:23.635 2025-07-31 02:00:23.638608 :B93D49A2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19812 pso_num=27 pso_serial#=242 2025-07-31 02:00:23.638740 :B93D49A3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=19812 osp_idx=25 osp_ver=210880 osp_pg=0 (spawn #439720) 2025-07-31 02:00:23.638741 :B93D49A4:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 02:00:23.638741 :B93D49A5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=161 time=2607999517 2025-07-31 02:00:23.638742 :B93D49A6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 02:00:23.638742 :B93D49A7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 02:00:23.638742 :B93D49A8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 02:00:23.638743 :B93D49A9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 02:00:23.638743 :B93D49AA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 02:00:23.639235 :B93D49AB: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-07-31 02:00:23.639278 :B93D49B1: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-07-31 02:00:23.646558 :B93D49BF: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-07-31 02:00:23.647628 :B93D49C7: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-07-31 02:00:23.648204 :B93D49CF: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-07-31 02:00:23.648657 :B93D49D7: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-07-31 02:00:23.649092 :B93D49DF: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-07-31 02:00:23.651560 :B93D49E7: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-07-31 02:00:23.652723 :B93D49EF: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-07-31 02:00:23.702670 :B93D49F7: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-07-31 02:00:23.703569 :B93D49FF: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-07-31 02:00:23.703810 :B93D4A07: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-07-31 02:00:23.704024 :B93D4A0F: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-07-31 02:00:23.705862 :B93D4A17:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 03:00:31.254879 :B941FBA0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 03:00:31.244 2025-07-31 03:00:31.254881 :B941FBA1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1740 pso_num=27 pso_serial#=243 2025-07-31 03:00:31.255017 :B941FBA2:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=1740 osp_idx=25 osp_ver=210881 osp_pg=0 (spawn #439858) 2025-07-31 03:00:31.255017 :B941FBA3:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 03:00:31.255018 :B941FBA4:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=162 time=2611607142 2025-07-31 03:00:31.255018 :B941FBA5:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 03:00:31.255019 :B941FBA6:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 03:00:31.255019 :B941FBA7:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 03:00:31.255019 :B941FBA8:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 03:00:31.255020 :B941FBA9:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 03:00:31.255508 :B941FBAA: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-07-31 03:00:31.255554 :B941FBB0: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-07-31 03:00:31.263397 :B941FBBE: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-07-31 03:00:31.264574 :B941FBC6: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-07-31 03:00:31.265163 :B941FBCE: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-07-31 03:00:31.265630 :B941FBD6: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-07-31 03:00:31.266060 :B941FBDE: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-07-31 03:00:31.268536 :B941FBE6: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-07-31 03:00:31.269637 :B941FBEE: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-07-31 03:00:31.319288 :B941FBF6: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-07-31 03:00:31.320181 :B941FBFE: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-07-31 03:00:31.320417 :B941FC06: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-07-31 03:00:31.320632 :B941FC0E: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-07-31 03:00:31.322574 :B941FC16:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 04:00:38.554887 :B946BDCB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 04:00:38.541 2025-07-31 04:00:38.554889 :B946BDCC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12112 pso_num=27 pso_serial#=244 2025-07-31 04:00:38.555022 :B946BDCD:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12112 osp_idx=25 osp_ver=210882 osp_pg=0 (spawn #439995) 2025-07-31 04:00:38.555022 :B946BDCE:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 04:00:38.555023 :B946BDCF:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=163 time=2615214439 2025-07-31 04:00:38.555023 :B946BDD0:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 04:00:38.555024 :B946BDD1:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 04:00:38.555024 :B946BDD2:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 04:00:38.555024 :B946BDD3:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 04:00:38.555025 :B946BDD4:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 04:00:38.555532 :B946BDD5: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-07-31 04:00:38.555574 :B946BDDB: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-07-31 04:00:38.562962 :B946BDF5: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-07-31 04:00:38.564019 :B946BDFD: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-07-31 04:00:38.564673 :B946BE05: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-07-31 04:00:38.565151 :B946BE0D: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-07-31 04:00:38.565615 :B946BE15: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-07-31 04:00:38.568081 :B946BE1D: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-07-31 04:00:38.569244 :B946BE25: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-07-31 04:00:38.619049 :B946BE2D: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-07-31 04:00:38.619947 :B946BE35: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-07-31 04:00:38.620182 :B946BE3D: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-07-31 04:00:38.620393 :B946BE45: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-07-31 04:00:38.622270 :B946BE4D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:38.580678 :B95BE6AB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:38.566 2025-07-31 08:24:38.580680 :B95BE6AC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16928 pso_num=27 pso_serial#=245 2025-07-31 08:24:38.580812 :B95BE6AD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16928 (legacy spawn) 2025-07-31 08:24:38.587229 :B95BE6AE: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-07-31 08:24:38.587641 :B95BE6B7: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-07-31 08:24:38.588453 :B95BE6B8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:38.597196 :B95BE6BB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:38.581 2025-07-31 08:24:38.597197 :B95BE6BC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20136 pso_num=27 pso_serial#=246 2025-07-31 08:24:38.597341 :B95BE6BD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20136 (legacy spawn) 2025-07-31 08:24:38.603805 :B95BE6BE: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-07-31 08:24:38.604119 :B95BE6C7: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-07-31 08:24:38.604913 :B95BE6C8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:40.061730 :B95BE92F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:40.050 2025-07-31 08:24:40.061732 :B95BE930:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14932 pso_num=27 pso_serial#=247 2025-07-31 08:24:40.061911 :B95BE931:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14932 osp_idx=25 osp_ver=210885 osp_pg=0 (spawn #440607) 2025-07-31 08:24:40.061912 :B95BE932:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 08:24:40.061913 :B95BE933:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=164 time=2631055954 2025-07-31 08:24:40.061913 :B95BE934:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 08:24:40.061914 :B95BE935:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 08:24:40.061914 :B95BE936:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 08:24:40.061914 :B95BE937:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 08:24:40.061915 :B95BE938:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 08:24:40.062809 :B95BE939: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-07-31 08:24:40.062853 :B95BE93F: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-07-31 08:24:40.164449 :B95BE97D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:41.597557 :B95BEA03:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:41.583 2025-07-31 08:24:41.597560 :B95BEA04:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20208 pso_num=27 pso_serial#=248 2025-07-31 08:24:41.597706 :B95BEA05:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20208 (legacy spawn) 2025-07-31 08:24:41.604174 :B95BEA06: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-07-31 08:24:41.604353 :B95BEA0F: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-07-31 08:24:41.605139 :B95BEA10:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:41.613880 :B95BEA13:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:41.599 2025-07-31 08:24:41.613882 :B95BEA14:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18048 pso_num=27 pso_serial#=249 2025-07-31 08:24:41.614051 :B95BEA15:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18048 (legacy spawn) 2025-07-31 08:24:41.620679 :B95BEA16: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-07-31 08:24:41.620851 :B95BEA1E: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-07-31 08:24:41.621683 :B95BEA20:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:41.690783 :B95BEA38:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:41.677 2025-07-31 08:24:41.690786 :B95BEA39:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16800 pso_num=27 pso_serial#=250 2025-07-31 08:24:41.690924 :B95BEA3A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16800 (legacy spawn) 2025-07-31 08:24:41.697565 :B95BEA3B: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-07-31 08:24:41.697735 :B95BEA44: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-07-31 08:24:41.698535 :B95BEA45:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:41.717886 :B95BEA48:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:41.708 2025-07-31 08:24:41.717889 :B95BEA49:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19068 pso_num=27 pso_serial#=251 2025-07-31 08:24:41.718078 :B95BEA4A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19068 (legacy spawn) 2025-07-31 08:24:41.724778 :B95BEA4B: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-07-31 08:24:41.724955 :B95BEA54: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-07-31 08:24:41.725837 :B95BEA55:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-31 08:24:42.251176 :B95BEA91:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-31 08:24:42.240 2025-07-31 08:24:42.251178 :B95BEA92:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13028 pso_num=27 pso_serial#=252 2025-07-31 08:24:42.251306 :B95BEA93:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13028 osp_idx=25 osp_ver=210890 osp_pg=0 (spawn #440609) 2025-07-31 08:24:42.251306 :B95BEA94:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-31 08:24:42.251306 :B95BEA95:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=165 time=2631058142 2025-07-31 08:24:42.251307 :B95BEA96:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-31 08:24:42.251307 :B95BEA97:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-31 08:24:42.251307 :B95BEA98:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-31 08:24:42.251308 :B95BEA99:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-31 08:24:42.251308 :B95BEA9A:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-31 08:24:42.251889 :B95BEA9B: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-07-31 08:24:42.251932 :B95BEAA1: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: 13028, M002)