Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250718220005\orcl_m000_18400_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:36219M/63366M, Ph+PgF:42149M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 32 Windows thread id: 18400, image: ORACLE.EXE (M000) *** 2025-07-18 22:00:05.367 *** SESSION ID:(2.1990) 2025-07-18 22:00:05.367 *** 2025-07-18 22:00:05.367 Process diagnostic dump for ORACLE.EXE (M000), OS id=18400, pid: 32, proc_ser: 196, sid: 2, sess_ser: 1990 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.315811 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.315837 sec since last wait 0: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=77 seq_num=78 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=76 seq_num=77 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.000005 sec of elapsed time 2: waited for 'control file sequential read' file#=0x0, block#=0x10, blocks=0x1 wait_id=75 seq_num=76 snap_id=1 wait times: snap=0.000090 sec, exc=0.000090 sec, total=0.000090 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000014 sec of elapsed time 3: waited for 'control file sequential read' file#=0x0, block#=0x1, blocks=0x1 wait_id=74 seq_num=75 snap_id=1 wait times: snap=0.000122 sec, exc=0.000122 sec, total=0.000122 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.003715 sec of elapsed time 4: waited for 'control file sequential read' file#=0x0, block#=0x13f, blocks=0x1 wait_id=73 seq_num=74 snap_id=1 wait times: snap=0.000054 sec, exc=0.000054 sec, total=0.000054 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=72 seq_num=73 snap_id=1 wait times: snap=0.000056 sec, exc=0.000056 sec, total=0.000056 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 6: waited for 'control file sequential read' file#=0x0, block#=0x10, blocks=0x1 wait_id=71 seq_num=72 snap_id=1 wait times: snap=0.000057 sec, exc=0.000057 sec, total=0.000057 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 7: waited for 'control file sequential read' file#=0x0, block#=0x1, blocks=0x1 wait_id=70 seq_num=71 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000033 sec of elapsed time 8: waited for 'control file sequential read' file#=0x0, block#=0x3, blocks=0x1 wait_id=69 seq_num=70 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000022 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x13, blocks=0x1 wait_id=68 seq_num=69 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time Sampled Session History of session 2 serial 1990 --------------------------------------------------- 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, 22:00:05] not in wait at each sample [1 sample, 22:00:04] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-18 22:00:05.367 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 32 (osid: 18400, M000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-14 22:00:02.499506 :B20B911A:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=4696 pso_num=32 pso_serial#=174 2025-07-14 22:00:02.499639 :B20B911B:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=4696 (legacy spawn) 2025-07-14 22:00:02.506398 :B20B911C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:02.506563 :B20B9125:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-14 22:00:02.507354 :B20B9126:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-14 22:00:02.516021 :B20B9129:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-14 22:00:02.502 2025-07-14 22:00:02.516023 :B20B912A:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17932 pso_num=32 pso_serial#=175 2025-07-14 22:00:02.516163 :B20B912B:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=17932 (legacy spawn) 2025-07-14 22:00:02.536555 :B20B912C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:02.536703 :B20B9135:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-14 22:00:02.537531 :B20B9136:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-14 22:00:20.021914 :B20B9DF5:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-14 22:00:20.009 2025-07-14 22:00:20.021916 :B20B9DF6:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=6572 pso_num=32 pso_serial#=176 2025-07-14 22:00:20.022052 :B20B9DF7:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=6572 osp_idx=30 osp_ver=685 osp_pg=0 (spawn #385839) 2025-07-14 22:00:20.022053 :B20B9DF8:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 22:00:20.022053 :B20B9DF9:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=145 time=1211195908 2025-07-14 22:00:20.022054 :B20B9DFA:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 22:00:20.022054 :B20B9DFB:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 22:00:20.022056 :B20B9DFC:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 22:00:20.022057 :B20B9DFD:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 22:00:20.022057 :B20B9DFE:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 22:00:20.022539 :B20B9DFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.022583 :B20B9E05:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-14 22:00:20.030109 :B20B9E13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.031164 :B20B9E1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.031716 :B20B9E23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.032273 :B20B9E2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.032725 :B20B9E33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.035229 :B20B9E3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.036368 :B20B9E43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.086026 :B20B9E4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.086929 :B20B9E53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.087179 :B20B9E5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.087395 :B20B9E63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-14 22:00:20.089345 :B20B9E6B:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-14 23:59:15.781119 :B215087A:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-14 23:59:15.769 2025-07-14 23:59:15.781121 :B215087B:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16824 pso_num=32 pso_serial#=177 2025-07-14 23:59:15.781236 :B215087C:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=16824 osp_idx=30 osp_ver=686 osp_pg=0 (spawn #386112) 2025-07-14 23:59:15.781236 :B215087D:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-14 23:59:15.781237 :B215087E:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=146 time=1218331673 2025-07-14 23:59:15.781237 :B215087F:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-07-14 23:59:15.781238 :B2150880:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-07-14 23:59:15.781238 :B2150881:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-07-14 23:59:15.781238 :B2150882:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-14 23:59:15.781239 :B2150883:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-14 23:59:15.781843 :B2150884:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-14 23:59:45.841198 :B2151436:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-14 23:59:55.810497 :B21517C0:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-14 23:59:55.803 2025-07-14 23:59:55.810500 :B21517C1:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=12684 pso_num=32 pso_serial#=178 2025-07-14 23:59:55.810686 :B21517C2:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=12684 (legacy spawn) 2025-07-14 23:59:55.824952 :B21517D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 00:00:28.852786 :B2152553:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-15 23:59:19.391280 :B2871F87:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-15 23:59:19.377 2025-07-15 23:59:19.391281 :B2871F88:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=8712 pso_num=32 pso_serial#=179 2025-07-15 23:59:19.391431 :B2871F89:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=8712 (legacy spawn) 2025-07-15 23:59:19.398347 :B2871F8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 23:59:19.398518 :B2871F8F:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-15 23:59:19.399321 :B2871F94:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-15 23:59:19.416730 :B2871F97:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-15 23:59:19.409 2025-07-15 23:59:19.416732 :B2871F98:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=15000 pso_num=32 pso_serial#=180 2025-07-15 23:59:19.416876 :B2871F99:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=15000 (legacy spawn) 2025-07-15 23:59:19.423601 :B2871F9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 23:59:19.423769 :B2871FA3:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-15 23:59:19.424711 :B2871FA4:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-15 23:59:22.346970 :B28720BB:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-15 23:59:22.340 2025-07-15 23:59:22.346972 :B28720BC:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17496 pso_num=32 pso_serial#=181 2025-07-15 23:59:22.347114 :B28720BD:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=17496 (legacy spawn) 2025-07-15 23:59:22.362318 :B28720BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 23:59:55.515161 :B2872CE7:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-15 23:59:55.994491 :B2872D09:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-15 23:59:55.981 2025-07-15 23:59:55.994493 :B2872D0A:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=14788 pso_num=32 pso_serial#=182 2025-07-15 23:59:55.994629 :B2872D0B:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=14788 (legacy spawn) 2025-07-15 23:59:56.001190 :B2872D0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 23:59:56.001365 :B2872D15:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-15 23:59:56.002181 :B2872D16:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-15 23:59:56.020902 :B2872D19:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-15 23:59:56.012 2025-07-15 23:59:56.020904 :B2872D1A:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=10520 pso_num=32 pso_serial#=183 2025-07-15 23:59:56.021047 :B2872D1B:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=10520 (legacy spawn) 2025-07-15 23:59:56.027672 :B2872D1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-15 23:59:56.027883 :B2872D22:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-15 23:59:56.028691 :B2872D26:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-15 23:59:59.095386 :B2872E30:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-15 23:59:59.085 2025-07-15 23:59:59.095388 :B2872E31:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17100 pso_num=32 pso_serial#=184 2025-07-15 23:59:59.095532 :B2872E32:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=17100 (legacy spawn) 2025-07-15 23:59:59.109598 :B2872E33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 00:06:19.076914 :B287B489:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 00:06:19.077072 :B287B492:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-16 00:06:19.078333 :B287B493:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-16 22:00:04.351568 :B2EE9602:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-16 22:00:04.345 2025-07-16 22:00:04.351570 :B2EE9603:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=11464 pso_num=32 pso_serial#=185 2025-07-16 22:00:04.352273 :B2EE9604:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=11464 (legacy spawn) 2025-07-16 22:00:04.364991 :B2EE9605:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 22:00:04.365162 :B2EE960D:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-16 22:00:04.366075 :B2EE960F:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-16 23:59:14.935711 :B2F7DEF3:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-16 23:59:14.922 2025-07-16 23:59:14.935714 :B2F7DEF4:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17352 pso_num=32 pso_serial#=186 2025-07-16 23:59:14.935889 :B2F7DEF5:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=17352 (legacy spawn) 2025-07-16 23:59:14.951049 :B2F7DEF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 23:59:48.465756 :B2F7EAF7:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-16 23:59:49.001220 :B2F7EB4E:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-16 23:59:48.994 2025-07-16 23:59:49.001222 :B2F7EB4F:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=18312 pso_num=32 pso_serial#=187 2025-07-16 23:59:49.001402 :B2F7EB50:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=18312 osp_idx=30 osp_ver=696 osp_pg=0 (spawn #392782) 2025-07-16 23:59:49.001403 :B2F7EB51:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 23:59:49.001404 :B2F7EB52:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=147 time=1391164892 2025-07-16 23:59:49.001404 :B2F7EB53:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 23:59:49.001404 :B2F7EB54:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 23:59:49.001405 :B2F7EB55:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 23:59:49.001405 :B2F7EB56:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 23:59:49.001406 :B2F7EB57:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 23:59:49.002083 :B2F7EB58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 23:59:49.002821 :B2F7EB6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 23:59:49.011546 :B2F7EB89:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:psdpgi.c:2235 2025-07-16 23:59:49.011981 :B2F7EB8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 23:59:49.012017 :B2F7EB8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:psdpgi.c:2235 2025-07-16 23:59:49.012973 :B2F7EB93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 23:59:49.013100 :B2F7EB9C:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 00:00:19.068515 :B2F7F5CB:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 22:00:03.810555 :B3607ED6:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 22:00:03.795 2025-07-17 22:00:03.810556 :B3607ED7:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=13920 pso_num=32 pso_serial#=188 2025-07-17 22:00:03.810687 :B3607ED8:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=13920 (legacy spawn) 2025-07-17 22:00:03.817153 :B3607ED9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 22:00:03.817355 :B3607EE1:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 22:00:03.818144 :B3607EE3:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 22:00:03.826903 :B3607EE6:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 22:00:03.826 2025-07-17 22:00:03.826904 :B3607EE7:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=1388 pso_num=32 pso_serial#=189 2025-07-17 22:00:03.827038 :B3607EE8:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=1388 (legacy spawn) 2025-07-17 22:00:03.833481 :B3607EE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 22:00:03.833646 :B3607EF2:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 22:00:03.834427 :B3607EF3:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 22:00:07.007333 :B36081C9:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 22:00:06.993 2025-07-17 22:00:07.007335 :B36081CA:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=19192 pso_num=32 pso_serial#=190 2025-07-17 22:00:07.007461 :B36081CB:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=19192 (legacy spawn) 2025-07-17 22:00:07.027261 :B36081F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 22:00:07.027418 :B36081F8:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 22:00:07.028259 :B36081F9:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 22:00:07.037621 :B36081FC:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 22:00:07.024 2025-07-17 22:00:07.037624 :B36081FD:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=12040 pso_num=32 pso_serial#=191 2025-07-17 22:00:07.037783 :B36081FE:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=12040 (legacy spawn) 2025-07-17 22:00:07.044374 :B36081FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 22:00:07.044516 :B3608208:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 22:00:07.045302 :B3608209:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 23:59:14.042256 :B36A1226:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 23:59:14.033 2025-07-17 23:59:14.042258 :B36A1227:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=18436 pso_num=32 pso_serial#=192 2025-07-17 23:59:14.042402 :B36A1228:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=18436 (legacy spawn) 2025-07-17 23:59:14.049181 :B36A1229:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 23:59:14.049352 :B36A122E:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 23:59:14.064976 :B36A1233:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 23:59:14.200386 :B36A1241:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 23:59:14.189 2025-07-17 23:59:14.200389 :B36A1242:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=12272 pso_num=32 pso_serial#=193 2025-07-17 23:59:14.200540 :B36A1243:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=12272 (legacy spawn) 2025-07-17 23:59:14.215653 :B36A1244:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 23:59:47.368994 :B36A1E35:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 23:59:54.493977 :B36A20C3:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 23:59:54.484 2025-07-17 23:59:54.493979 :B36A20C4:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=19072 pso_num=32 pso_serial#=194 2025-07-17 23:59:54.494133 :B36A20C5:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=19072 (legacy spawn) 2025-07-17 23:59:54.520828 :B36A20C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 23:59:54.521019 :B36A20CF:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-17 23:59:54.522003 :B36A20D0:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-17 23:59:56.613332 :B36A21A7:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-17 23:59:56.609 2025-07-17 23:59:56.613334 :B36A21A8:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=19000 pso_num=32 pso_serial#=195 2025-07-17 23:59:56.613476 :B36A21A9:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=19000 (legacy spawn) 2025-07-17 23:59:56.627923 :B36A21AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 00:00:29.646533 :B36A3051:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-18 22:00:04.904268 :B3D4731E:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-18 22:00:04.899 2025-07-18 22:00:04.904270 :B3D4731F:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=18400 pso_num=32 pso_serial#=196 2025-07-18 22:00:04.904405 :B3D47320:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=18400 osp_idx=30 osp_ver=705 osp_pg=0 (spawn #399164) 2025-07-18 22:00:04.904406 :B3D47321:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 22:00:04.904406 :B3D47322:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=148 time=1556780798 2025-07-18 22:00:04.904407 :B3D47323:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 22:00:04.904407 :B3D47324:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 22:00:04.904407 :B3D47325:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 22:00:04.904407 :B3D47326:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 22:00:04.904407 :B3D47327:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 22:00:04.904888 :B3D47328:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.904932 :B3D4732F:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-18 22:00:04.909405 :B3D47341:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.932017 :B3D47354:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.946911 :B3D4735C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.977430 :B3D47364:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.981243 :B3D4736C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.985050 :B3D47374:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.989341 :B3D4737C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:04.990403 :B3D47384:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 32 (osid: 18400, M000)