Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719060010\orcl_m000_16604_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:36218M/63366M, Ph+PgF:42143M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 32 Windows thread id: 16604, image: ORACLE.EXE (M000) *** 2025-07-19 06:00:10.502 *** SESSION ID:(2.2005) 2025-07-19 06:00:10.502 *** 2025-07-19 06:00:10.502 Process diagnostic dump for ORACLE.EXE (M000), OS id=16604, pid: 32, proc_ser: 201, sid: 2, sess_ser: 2005 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 1.079983 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.080010 sec since last wait 0: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=78 seq_num=79 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'control file sequential read' file#=0x0, block#=0x12, 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.000005 sec of elapsed time 2: waited for 'control file sequential read' file#=0x0, block#=0x10, blocks=0x1 wait_id=76 seq_num=77 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.000021 sec of elapsed time 3: waited for 'control file sequential read' file#=0x0, block#=0x1, blocks=0x1 wait_id=75 seq_num=76 snap_id=1 wait times: snap=0.000144 sec, exc=0.000144 sec, total=0.000144 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.004094 sec of elapsed time 4: waited for 'control file sequential read' file#=0x0, block#=0x13f, blocks=0x1 wait_id=74 seq_num=75 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 5: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=73 seq_num=74 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.000005 sec of elapsed time 6: waited for 'control file sequential read' file#=0x0, block#=0x10, blocks=0x1 wait_id=72 seq_num=73 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 7: waited for 'control file sequential read' file#=0x0, block#=0x1, blocks=0x1 wait_id=71 seq_num=72 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000033 sec of elapsed time 8: waited for 'control file sequential read' file#=0x0, block#=0x3, blocks=0x1 wait_id=70 seq_num=71 snap_id=1 wait times: snap=0.000059 sec, exc=0.000059 sec, total=0.000059 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000023 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x13, blocks=0x1 wait_id=69 seq_num=70 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 Sampled Session History of session 2 serial 2005 --------------------------------------------------- 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, 06:00:10] not in wait at each sample [1 sample, 06:00:09] idle wait at each sample [session created at: 06:00:09] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-19 06:00:10.502 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 32 (osid: 16604, M000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 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 2025-07-18 22:00:06.238304 :B3D4746A: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:06.294203 :B3D4747E: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:06.294532 :B3D47486: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:06.294794 :B3D4748E: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:06.294809 :B3D47491:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-18 22:00:06.294879 :B3D474A0: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-18 22:00:06.298468 :B3D474AA:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-18 23:59:21.935471 :B3DE065B:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-18 23:59:21.928 2025-07-18 23:59:21.935472 :B3DE065C:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16332 pso_num=32 pso_serial#=197 2025-07-18 23:59:21.935604 :B3DE065D:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=16332 osp_idx=30 osp_ver=706 osp_pg=0 (spawn #399437) 2025-07-18 23:59:21.935605 :B3DE065E: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-18 23:59:21.935605 :B3DE065F:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=149 time=1563937829 2025-07-18 23:59:21.935605 :B3DE0660:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 23:59:21.935606 :B3DE0661:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 23:59:21.935606 :B3DE0662:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 23:59:21.935607 :B3DE0663:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 23:59:21.935607 :B3DE0664:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 23:59:21.936144 :B3DE0665: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-18 23:59:21.936963 :B3DE067A: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 23:59:21.946018 :B3DE0696:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpus:psdpgi.c:2235 2025-07-18 23:59:21.946497 :B3DE0697: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 23:59:21.946566 :B3DE069A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:32:2] atxpop:psdpgi.c:2235 2025-07-18 23:59:21.947721 :B3DE06A0: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 23:59:21.947835 :B3DE06A9: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-18 23:59:52.037457 :B3DE117E:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-18 23:59:58.981331 :B3DE13F3:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-18 23:59:58.982 2025-07-18 23:59:58.981333 :B3DE13F4:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=15732 pso_num=32 pso_serial#=198 2025-07-18 23:59:58.981482 :B3DE13F5:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=15732 (legacy spawn) 2025-07-18 23:59:58.996736 :B3DE13F6: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-19 00:06:18.968771 :B3DEA17F: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-19 00:06:18.968943 :B3DEA188: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-19 00:06:18.970124 :B3DEA189:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-19 06:00:04.841172 :B3FAE57F:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-19 06:00:04.833 2025-07-19 06:00:04.841174 :B3FAE580:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=18696 pso_num=32 pso_serial#=199 2025-07-19 06:00:04.841313 :B3FAE581:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=18696 (legacy spawn) 2025-07-19 06:00:04.847808 :B3FAE582: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-19 06:00:04.847974 :B3FAE58B: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-19 06:00:04.848782 :B3FAE58C:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-19 06:00:05.788396 :B3FAE5C2:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-19 06:00:05.786 2025-07-19 06:00:05.788398 :B3FAE5C3:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17836 pso_num=32 pso_serial#=200 2025-07-19 06:00:05.789057 :B3FAE5C4:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=17836 (legacy spawn) 2025-07-19 06:00:05.800954 :B3FAE5C5: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-19 06:00:05.801112 :B3FAE5CE: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-19 06:00:05.801878 :B3FAE5CF:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-07-19 06:00:09.241145 :B3FAE866:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 07-19 06:00:09.236 2025-07-19 06:00:09.241148 :B3FAE867:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16604 pso_num=32 pso_serial#=201 2025-07-19 06:00:09.241280 :B3FAE868:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=16604 osp_idx=30 osp_ver=710 osp_pg=0 (spawn #400279) 2025-07-19 06:00:09.241281 :B3FAE869: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-19 06:00:09.241281 :B3FAE86A:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=150 time=1585585142 2025-07-19 06:00:09.241281 :B3FAE86B:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 06:00:09.241282 :B3FAE86C:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 06:00:09.241282 :B3FAE86D:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 06:00:09.241282 :B3FAE86E:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 06:00:09.241283 :B3FAE86F:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 06:00:09.241824 :B3FAE871: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-19 06:00:09.241865 :B3FAE877: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-19 06:00:09.246383 :B3FAE88C: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-19 06:00:09.269777 :B3FAE89D: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-19 06:00:09.284677 :B3FAE8AD: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-19 06:00:09.315123 :B3FAE8C7: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-19 06:00:09.318936 :B3FAE8D1: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-19 06:00:09.322835 :B3FAE8DD: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-19 06:00:09.327192 :B3FAE8E7: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-19 06:00:09.328738 :B3FAE8F1: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-19 06:00:09.357081 :B3FAE90B:db_trace:ksl2.c@2477:kslwtbctx(): [10005:32:2] KSL WAIT BEG [latch: cache buffers chains] 33158553976/0x7b8676178 150/0x96 0/0x0 wait_id=33 seq_num=34 snap_id=1 2025-07-19 06:00:09.387209 :B3FAE91E:db_trace:ksl2.c@2611:kslwtectx(): [10005:32:2] KSL WAIT END [latch: cache buffers chains] 33158553976/0x7b8676178 150/0x96 0/0x0 wait_id=33 seq_num=34 snap_id=1 2025-07-19 06:00:09.387211 :B3FAE91F:db_trace:ksl2.c@2619:kslwtectx(): [10005:32:2] KSL WAIT END wait times (usecs) - snap=30125, exc=30125, tot=30125 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 32 (osid: 16604, M000)