Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250709080942\orcl_m002_17496_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:36514M/63366M, Ph+PgF:42624M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 17496, image: ORACLE.EXE (M002) *** 2025-07-09 08:09:42.427 *** SESSION ID:(219.27853) 2025-07-09 08:09:42.427 *** 2025-07-09 08:09:42.427 Process diagnostic dump for ORACLE.EXE (M002), OS id=17496, pid: 27, proc_ser: 55, sid: 219, sess_ser: 27853 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=486 seq_num=487 snap_id=1 wait times: snap=0.001905 sec, exc=0.001905 sec, total=0.001905 sec wait times: max=infinite, heur=0.587409 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.000007 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=485 seq_num=486 snap_id=1 wait times: snap=0.001144 sec, exc=0.001144 sec, total=0.001144 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=484 seq_num=485 snap_id=1 wait times: snap=0.000060 sec, exc=0.000060 sec, total=0.000060 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=483 seq_num=484 snap_id=1 wait times: snap=0.001693 sec, exc=0.001693 sec, total=0.001693 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=482 seq_num=483 snap_id=1 wait times: snap=0.000075 sec, exc=0.000075 sec, total=0.000075 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=481 seq_num=482 snap_id=1 wait times: snap=0.000100 sec, exc=0.000100 sec, total=0.000100 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000012 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=480 seq_num=481 snap_id=1 wait times: snap=0.000739 sec, exc=0.000739 sec, total=0.000739 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000015 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=479 seq_num=480 snap_id=1 wait times: snap=0.001153 sec, exc=0.001153 sec, total=0.001153 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=478 seq_num=479 snap_id=1 wait times: snap=0.003654 sec, exc=0.003654 sec, total=0.003654 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=477 seq_num=478 snap_id=1 wait times: snap=0.001064 sec, exc=0.001064 sec, total=0.001064 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=486 seq_num=487 snap_id=1 wait times: snap=0.002030 sec, exc=0.002030 sec, total=0.002030 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 219 serial 27853 --------------------------------------------------- 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:09:42] waited for 'ADR block file read', seq_num: 447 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 08:09:41] idle wait at each sample [session created at: 08:09:41] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 08:09:42 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-09 08:09:42.427 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 17496, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-09 01:58:54.611325 :AF7577B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-09 01:58:54.611530 :AF7577B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-09 01:58:54.611542 :AF7577B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-09 01:58:54.614328 :AF7577B3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 01:58:57.196268 :AF757865:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 01:58:57.182 2025-07-09 01:58:57.196270 :AF757866:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1216 pso_num=27 pso_serial#=44 2025-07-09 01:58:57.196404 :AF757867:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1216 (legacy spawn) 2025-07-09 01:58:57.202816 :AF757868: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-09 01:58:57.202993 :AF75786D: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-09 01:58:57.203783 :AF757872:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 01:58:57.212476 :AF757875:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 01:58:57.197 2025-07-09 01:58:57.212479 :AF757876:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16116 pso_num=27 pso_serial#=45 2025-07-09 01:58:57.212619 :AF757877:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16116 (legacy spawn) 2025-07-09 01:58:57.219073 :AF757878: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-09 01:58:57.219236 :AF757881: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-09 01:58:57.220017 :AF757882:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 01:59:00.585587 :AF75798D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 01:59:00.584 2025-07-09 01:59:00.585590 :AF75798E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18156 pso_num=27 pso_serial#=46 2025-07-09 01:59:00.585735 :AF75798F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18156 (legacy spawn) 2025-07-09 01:59:00.592545 :AF757990: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-09 01:59:00.845702 :AF7579BC: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-09 01:59:00.846593 :AF7579CF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 01:59:00.872115 :AF7579D5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 01:59:00.865 2025-07-09 01:59:00.872117 :AF7579D6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11572 pso_num=27 pso_serial#=47 2025-07-09 01:59:00.872258 :AF7579D7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11572 (legacy spawn) 2025-07-09 01:59:00.878955 :AF7579D8: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-09 01:59:00.879129 :AF7579E1: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-09 01:59:00.879952 :AF7579E2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 01:59:19.164245 :AF757F90:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 01:59:19.155 2025-07-09 01:59:19.164248 :AF757F91:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18176 pso_num=27 pso_serial#=48 2025-07-09 01:59:19.164389 :AF757F92:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18176 (legacy spawn) 2025-07-09 01:59:19.170917 :AF757F93: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-09 01:59:19.171101 :AF757F98: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-09 01:59:19.171953 :AF757F9D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 02:00:00.040199 :AF758DAF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 02:00:00.030 2025-07-09 02:00:00.040201 :AF758DB0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14080 pso_num=27 pso_serial#=49 2025-07-09 02:00:00.040342 :AF758DB1:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14080 osp_idx=25 osp_ver=144897 osp_pg=0 (spawn #366387) 2025-07-09 02:00:00.040343 :AF758DB2: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-09 02:00:00.040343 :AF758DB3:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=246 time=707175923 2025-07-09 02:00:00.040344 :AF758DB4:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 02:00:00.040344 :AF758DB5:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 02:00:00.040345 :AF758DB6:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 02:00:00.040346 :AF758DB7:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 02:00:00.040347 :AF758DB8:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 02:00:00.040858 :AF758DB9: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-09 02:00:00.040911 :AF758DC0: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-09 02:00:00.041033 :AF758DD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-09 02:00:00.045011 :AF758DE2: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-09 02:00:00.098876 :AF758DED: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-09 02:00:00.100379 :AF758DF5: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-09 02:00:00.101764 :AF758DFD: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-09 02:00:00.102840 :AF758E05: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-09 02:00:00.102865 :AF758E08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-09 02:00:00.102907 :AF758E13:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-09 02:00:00.103522 :AF758E1E: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-09 02:00:00.103535 :AF758E21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-09 02:00:00.103557 :AF758E2D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-09 02:00:00.104366 :AF758E37: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-09 02:00:00.104380 :AF758E39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-09 02:00:00.104404 :AF758E45:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-09 02:00:00.104470 :AF758E51: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-09 02:00:00.105856 :AF758E52:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 02:00:32.215716 :AF759A44:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 02:00:32.201 2025-07-09 02:00:32.215719 :AF759A45:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12780 pso_num=27 pso_serial#=50 2025-07-09 02:00:32.215857 :AF759A46:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12780 osp_idx=25 osp_ver=144898 osp_pg=0 (spawn #366389) 2025-07-09 02:00:32.215858 :AF759A47: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-09 02:00:32.215858 :AF759A48:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=247 time=707208095 2025-07-09 02:00:32.215859 :AF759A49:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 02:00:32.215859 :AF759A4A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 02:00:32.215860 :AF759A4B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 02:00:32.215860 :AF759A4C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 02:00:32.215860 :AF759A4D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 02:00:32.216353 :AF759A4E: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-09 02:00:32.216396 :AF759A54: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-09 02:00:32.224407 :AF759A62: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-09 02:00:32.225457 :AF759A6A: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-09 02:00:32.226008 :AF759A72: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-09 02:00:32.226559 :AF759A7A: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-09 02:00:32.227003 :AF759A82: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-09 02:00:32.229445 :AF759A8A: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-09 02:00:32.230607 :AF759A92: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-09 02:00:32.280517 :AF759A9A: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-09 02:00:32.281427 :AF759AA2: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-09 02:00:32.281660 :AF759AAA: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-09 02:00:32.281876 :AF759AB2: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-09 02:00:32.283790 :AF759ABA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 03:00:40.947577 :AF7A5BF1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 03:00:40.936 2025-07-09 03:00:40.947579 :AF7A5BF2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17532 pso_num=27 pso_serial#=51 2025-07-09 03:00:40.947713 :AF7A5BF3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17532 osp_idx=25 osp_ver=144899 osp_pg=0 (spawn #366527) 2025-07-09 03:00:40.947713 :AF7A5BF4: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-09 03:00:40.947714 :AF7A5BF5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=248 time=710816829 2025-07-09 03:00:40.947714 :AF7A5BF6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 03:00:40.947715 :AF7A5BF7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 03:00:40.947715 :AF7A5BF8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 03:00:40.947715 :AF7A5BF9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 03:00:40.947716 :AF7A5BFA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 03:00:40.948251 :AF7A5BFB: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-09 03:00:40.948297 :AF7A5C01: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-09 03:00:40.955480 :AF7A5C0F: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-09 03:00:40.956616 :AF7A5C17: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-09 03:00:40.957198 :AF7A5C1F: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-09 03:00:40.957673 :AF7A5C27: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-09 03:00:40.958102 :AF7A5C2F: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-09 03:00:40.960702 :AF7A5C37: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-09 03:00:40.961839 :AF7A5C3F: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-09 03:00:41.011740 :AF7A5C47: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-09 03:00:41.012985 :AF7A5C4F: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-09 03:00:41.013887 :AF7A5C57: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-09 03:00:41.014257 :AF7A5C8F: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-09 03:00:41.014527 :AF7A5C97: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-09 03:00:41.016481 :AF7A5C9F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 04:00:49.108348 :AF7F2066:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 04:00:49.093 2025-07-09 04:00:49.108350 :AF7F2067:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13012 pso_num=27 pso_serial#=52 2025-07-09 04:00:49.108483 :AF7F2068:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13012 osp_idx=25 osp_ver=144900 osp_pg=0 (spawn #366664) 2025-07-09 04:00:49.108484 :AF7F2069: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-09 04:00:49.108485 :AF7F206A:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=249 time=714424986 2025-07-09 04:00:49.108485 :AF7F206B:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 04:00:49.108486 :AF7F206C:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 04:00:49.108486 :AF7F206D:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 04:00:49.108486 :AF7F206E:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 04:00:49.108487 :AF7F206F:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 04:00:49.109034 :AF7F209A: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-09 04:00:49.109079 :AF7F20A0: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-09 04:00:49.111660 :AF7F20AE: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-09 04:00:49.112587 :AF7F20B6:db_trace:ktu.c@4871:ktuaex1r(): [10442:27:219] Extension usn 4, ext 3, nex 6 2025-07-09 04:00:49.112593 :AF7F20B7:db_trace:ktusm.c@2117:ktusmasp(): [10445:27:219] des1:tsn:1 rdba:0x00810aaa where: ktspbwh1: ktspfsrch 2025-07-09 04:00:49.113783 :AF7F20B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=16 loc='ktm.h LINE:583 ID:ktmpsm' id1=0 id2=0 name= type=0 2025-07-09 04:00:49.117186 :AF7F20BA: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-09 04:00:49.118283 :AF7F20C2: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-09 04:00:49.118951 :AF7F20CA: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-09 04:00:49.119455 :AF7F20D2: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-09 04:00:49.119919 :AF7F20DA: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-09 04:00:49.122471 :AF7F20E2: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-09 04:00:49.123709 :AF7F20EA: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-09 04:00:49.173380 :AF7F21AF: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-09 04:00:49.174293 :AF7F21B7: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-09 04:00:49.174546 :AF7F21BF: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-09 04:00:49.174767 :AF7F21C7: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-09 04:00:49.176615 :AF7F21CF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 05:00:56.967423 :AF83E5D1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 05:00:56.963 2025-07-09 05:00:56.967425 :AF83E5D2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16680 pso_num=27 pso_serial#=53 2025-07-09 05:00:56.967558 :AF83E5D3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16680 osp_idx=25 osp_ver=144901 osp_pg=0 (spawn #366802) 2025-07-09 05:00:56.967559 :AF83E5D4: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-09 05:00:56.967559 :AF83E5D5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=250 time=718032845 2025-07-09 05:00:56.967560 :AF83E5D6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 05:00:56.967560 :AF83E5D7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 05:00:56.967561 :AF83E5D8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 05:00:56.967561 :AF83E5D9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 05:00:56.967561 :AF83E5DA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 05:00:56.968046 :AF83E5DB: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-09 05:00:56.968092 :AF83E5E1: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-09 05:00:56.976197 :AF83E5EF: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-09 05:00:56.977360 :AF83E5F7: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-09 05:00:56.977955 :AF83E5FF: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-09 05:00:56.978436 :AF83E607: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-09 05:00:56.978866 :AF83E60F: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-09 05:00:56.981405 :AF83E617: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-09 05:00:56.982510 :AF83E61F: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-09 05:00:57.032551 :AF83E627: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-09 05:00:57.033432 :AF83E62F: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-09 05:00:57.033671 :AF83E637: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-09 05:00:57.033886 :AF83E63F: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-09 05:00:57.035820 :AF83E647:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 08:09:39.483705 :AF9314AB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 08:09:39.482 2025-07-09 08:09:39.483706 :AF9314AC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18404 pso_num=27 pso_serial#=54 2025-07-09 08:09:39.483861 :AF9314B0:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18404 osp_idx=25 osp_ver=144902 osp_pg=0 (spawn #367239) 2025-07-09 08:09:39.483862 :AF9314B1: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-09 08:09:39.483862 :AF9314B2:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=251 time=729355361 2025-07-09 08:09:39.483863 :AF9314B3:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:09:39.483863 :AF9314B4:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:09:39.483863 :AF9314B5:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:09:39.483864 :AF9314B6:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:09:39.483864 :AF9314B7:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:09:39.485233 :AF9314F5: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-09 08:09:39.485274 :AF9314FB: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-09 08:09:39.610554 :AF931795:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-09 08:09:41.765300 :AF931871:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-09 08:09:41.757 2025-07-09 08:09:41.765301 :AF931872:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17496 pso_num=27 pso_serial#=55 2025-07-09 08:09:41.765437 :AF931873:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17496 osp_idx=25 osp_ver=144903 osp_pg=0 (spawn #367241) 2025-07-09 08:09:41.765438 :AF931874: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-09 08:09:41.765438 :AF931875:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=252 time=729357658 2025-07-09 08:09:41.765439 :AF931876:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 08:09:41.765439 :AF931877:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 08:09:41.765439 :AF931878:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 08:09:41.765439 :AF931879:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 08:09:41.765439 :AF93187A:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 08:09:41.765939 :AF93187B: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-09 08:09:41.765987 :AF931881: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: 17496, M002)