Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250609222310\orcl_m002_14460_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:36805M/63366M, Ph+PgF:43375M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 14460, image: ORACLE.EXE (M002) *** 2025-06-09 22:23:10.371 *** SESSION ID:(219.45891) 2025-06-09 22:23:10.371 *** 2025-06-09 22:23:10.371 Process diagnostic dump for ORACLE.EXE (M002), OS id=14460, pid: 27, proc_ser: 81, sid: 219, sess_ser: 45891 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.000145 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000171 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x7bca, blocks=0x1 wait_id=1820 seq_num=1821 snap_id=1 wait times: snap=0.001088 sec, exc=0.001088 sec, total=0.001088 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000149 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x7baa, blocks=0x1 wait_id=1819 seq_num=1820 snap_id=1 wait times: snap=0.000116 sec, exc=0.000116 sec, total=0.000116 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000195 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x7b6a, blocks=0x1 wait_id=1818 seq_num=1819 snap_id=1 wait times: snap=0.001452 sec, exc=0.001452 sec, total=0.001452 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x7b4a, blocks=0x1 wait_id=1817 seq_num=1818 snap_id=1 wait times: snap=0.000081 sec, exc=0.000081 sec, total=0.000081 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000181 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x7aea, blocks=0x1 wait_id=1816 seq_num=1817 snap_id=1 wait times: snap=0.000110 sec, exc=0.000110 sec, total=0.000110 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000135 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x7aca, blocks=0x1 wait_id=1815 seq_num=1816 snap_id=1 wait times: snap=0.000082 sec, exc=0.000082 sec, total=0.000082 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x7a9a, blocks=0x1 wait_id=1814 seq_num=1815 snap_id=1 wait times: snap=0.000123 sec, exc=0.000123 sec, total=0.000123 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x7a7a, blocks=0x1 wait_id=1813 seq_num=1814 snap_id=1 wait times: snap=0.000108 sec, exc=0.000108 sec, total=0.000108 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000143 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x8752, blocks=0x1 wait_id=1822 seq_num=1823 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.000152 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x7be2, blocks=0x1 wait_id=1821 seq_num=1822 snap_id=1 wait times: snap=0.000086 sec, exc=0.000086 sec, total=0.000086 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000193 sec of elapsed time Sampled Session History of session 219 serial 45891 --------------------------------------------------- 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:23:09] not in wait at each sample [1 sample, 22:23:08] idle wait at each sample [session created at: 22:23:09] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-09 22:23:10.371 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 14460, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-09 22:19:05.632625 :A2904CB9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8928 pso_num=27 pso_serial#=58 2025-06-09 22:19:05.632766 :A2904CBA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8928 (legacy spawn) 2025-06-09 22:19:05.639270 :A2904CBB: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-06-09 22:19:05.639443 :A2904CC4: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-06-09 22:19:05.640262 :A2904CC5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:17.502618 :A2905073:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:17.488 2025-06-09 22:19:17.502620 :A2905074:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12744 pso_num=27 pso_serial#=59 2025-06-09 22:19:17.502760 :A2905075:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12744 (legacy spawn) 2025-06-09 22:19:17.509254 :A2905076: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-06-09 22:19:17.509423 :A290507F: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-06-09 22:19:17.510249 :A2905080:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:17.519100 :A2905083:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:17.504 2025-06-09 22:19:17.519102 :A2905084:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14552 pso_num=27 pso_serial#=60 2025-06-09 22:19:17.519259 :A2905085:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14552 (legacy spawn) 2025-06-09 22:19:17.525737 :A2905086: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-06-09 22:19:17.525905 :A290508F: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-06-09 22:19:17.526727 :A2905090:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:20.195931 :A2905168:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:20.186 2025-06-09 22:19:20.195933 :A2905169:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15304 pso_num=27 pso_serial#=61 2025-06-09 22:19:20.196073 :A290516A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15304 (legacy spawn) 2025-06-09 22:19:20.202698 :A290516B: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-06-09 22:19:20.202864 :A2905170: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-06-09 22:19:20.203678 :A2905175:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:20.212316 :A2905178:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:20.201 2025-06-09 22:19:20.212318 :A2905179:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12012 pso_num=27 pso_serial#=62 2025-06-09 22:19:20.212451 :A290517A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12012 (legacy spawn) 2025-06-09 22:19:20.218987 :A290517B: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-06-09 22:19:20.219150 :A2905184: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-06-09 22:19:20.219951 :A2905185:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:20.698890 :A290519B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:20.686 2025-06-09 22:19:20.698892 :A290519C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16328 pso_num=27 pso_serial#=63 2025-06-09 22:19:20.699023 :A290519D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16328 (legacy spawn) 2025-06-09 22:19:20.705546 :A290519E: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-06-09 22:19:20.705714 :A29051A7: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-06-09 22:19:20.706534 :A29051A8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:20.715223 :A29051AB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:20.701 2025-06-09 22:19:20.715225 :A29051AC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12908 pso_num=27 pso_serial#=64 2025-06-09 22:19:20.715359 :A29051AD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12908 (legacy spawn) 2025-06-09 22:19:20.721940 :A29051BB: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-06-09 22:19:20.722107 :A29051C4: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-06-09 22:19:20.722934 :A29051C5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:56.886226 :A2905D8E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:56.873 2025-06-09 22:19:56.886229 :A2905D8F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15168 pso_num=27 pso_serial#=65 2025-06-09 22:19:56.886376 :A2905D90:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15168 (legacy spawn) 2025-06-09 22:19:56.892883 :A2905D91: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-06-09 22:19:56.893058 :A2905D9A: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-06-09 22:19:56.893881 :A2905D9B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:19:56.902643 :A2905D9E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:19:56.889 2025-06-09 22:19:56.902646 :A2905D9F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15940 pso_num=27 pso_serial#=66 2025-06-09 22:19:56.902785 :A2905DA0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15940 (legacy spawn) 2025-06-09 22:19:56.909364 :A2905DA1: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-06-09 22:19:56.909547 :A2905DAA: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-06-09 22:19:56.910346 :A2905DAB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:20:07.821287 :A2906137:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:20:07.809 2025-06-09 22:20:07.821289 :A2906138:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15264 pso_num=27 pso_serial#=67 2025-06-09 22:20:07.821425 :A2906139:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15264 (legacy spawn) 2025-06-09 22:20:07.841656 :A290613A: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-06-09 22:20:07.841824 :A290613F: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-06-09 22:20:07.842711 :A2906144:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:20:12.544871 :A290626C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:20:12.535 2025-06-09 22:20:12.544873 :A290626D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13372 pso_num=27 pso_serial#=68 2025-06-09 22:20:12.545011 :A290626E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13372 (legacy spawn) 2025-06-09 22:20:12.551604 :A290626F: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-06-09 22:20:12.551776 :A2906274: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-06-09 22:20:12.552601 :A2906279:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:21:06.203129 :A2907490:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:21:06.199 2025-06-09 22:21:06.203131 :A2907491:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13156 pso_num=27 pso_serial#=69 2025-06-09 22:21:06.203288 :A2907492:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13156 (legacy spawn) 2025-06-09 22:21:06.209678 :A2907493: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-06-09 22:21:06.209854 :A290749C: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-06-09 22:21:06.210708 :A290749D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:21:06.219479 :A29074A0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:21:06.215 2025-06-09 22:21:06.219481 :A29074A1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12668 pso_num=27 pso_serial#=70 2025-06-09 22:21:06.219620 :A29074A2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12668 (legacy spawn) 2025-06-09 22:21:06.226097 :A29074A3: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-06-09 22:21:06.226274 :A29074AC: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-06-09 22:21:06.227080 :A29074AD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:21:09.498871 :A2907598:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:21:09.485 2025-06-09 22:21:09.498872 :A2907599:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14488 pso_num=27 pso_serial#=71 2025-06-09 22:21:09.499016 :A290759A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14488 (legacy spawn) 2025-06-09 22:21:09.505848 :A290759B: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-06-09 22:21:09.506048 :A29075A2: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-06-09 22:21:09.506948 :A29075A5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:21:09.516368 :A29075A8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:21:09.516 2025-06-09 22:21:09.516370 :A29075A9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15108 pso_num=27 pso_serial#=72 2025-06-09 22:21:09.516529 :A29075AA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15108 (legacy spawn) 2025-06-09 22:21:09.523782 :A29075AB: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-06-09 22:21:09.523970 :A29075B4: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-06-09 22:21:09.524930 :A29075B5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:21:09.962502 :A2907603:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:21:09.954 2025-06-09 22:21:09.962504 :A2907604:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16068 pso_num=27 pso_serial#=73 2025-06-09 22:21:09.962636 :A2907605:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16068 (legacy spawn) 2025-06-09 22:21:09.969157 :A2907606: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-06-09 22:21:09.969324 :A290760F: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-06-09 22:21:09.970206 :A2907610:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:21:21.167710 :A2907978:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:21:21.159 2025-06-09 22:21:21.167712 :A2907979:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9964 pso_num=27 pso_serial#=74 2025-06-09 22:21:21.167845 :A290797A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9964 (legacy spawn) 2025-06-09 22:21:21.174292 :A290797B: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-06-09 22:21:21.174496 :A2907984: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-06-09 22:21:21.175324 :A2907985:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:22:00.970698 :A2908687:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:22:00.965 2025-06-09 22:22:00.970700 :A2908688:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16256 pso_num=27 pso_serial#=75 2025-06-09 22:22:00.970843 :A2908689:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16256 osp_idx=25 osp_ver=55664 osp_pg=0 (spawn #269210) 2025-06-09 22:22:00.970844 :A290868A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 22:22:00.970844 :A290868B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=8 time=2483464157 2025-06-09 22:22:00.970845 :A290868C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 22:22:00.970845 :A290868D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 22:22:00.970846 :A290868E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 22:22:00.970846 :A290868F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 22:22:00.970846 :A2908690:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 22:22:00.971362 :A2908691: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-06-09 22:22:00.971410 :A2908697: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-06-09 22:22:00.975543 :A29086B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-09 22:22:00.975586 :A29086B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-09 22:22:00.975851 :A29086B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-09 22:22:00.975869 :A29086B3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-09 22:22:00.976086 :A29086B4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-09 22:22:00.976100 :A29086B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-09 22:22:00.976313 :A29086B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-09 22:22:00.976326 :A29086B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-09 22:22:00.976533 :A29086B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-09 22:22:00.976546 :A29086B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-09 22:22:00.976748 :A29086BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-09 22:22:00.976761 :A29086BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-09 22:22:00.979054 :A29086BC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:22:18.787631 :A2908BD0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:22:18.784 2025-06-09 22:22:18.787634 :A2908BD1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15364 pso_num=27 pso_serial#=76 2025-06-09 22:22:18.787767 :A2908BD2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15364 (legacy spawn) 2025-06-09 22:22:18.794249 :A2908BD3: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-06-09 22:22:18.794428 :A2908BDB: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-06-09 22:22:18.795229 :A2908BDC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:22:18.803956 :A2908BDF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:22:18.799 2025-06-09 22:22:18.803959 :A2908BE0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12148 pso_num=27 pso_serial#=77 2025-06-09 22:22:18.804103 :A2908BE1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12148 (legacy spawn) 2025-06-09 22:22:18.810608 :A2908BE2: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-06-09 22:22:18.810773 :A2908BEB: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-06-09 22:22:18.811590 :A2908BEC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:22:22.104470 :A2908D24:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:22:22.091 2025-06-09 22:22:22.104473 :A2908D25:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12128 pso_num=27 pso_serial#=78 2025-06-09 22:22:22.104621 :A2908D26:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12128 (legacy spawn) 2025-06-09 22:22:22.111104 :A2908D27: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-06-09 22:22:22.111292 :A2908D2C: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-06-09 22:22:22.112103 :A2908D31:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:22:22.120894 :A2908D34:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:22:22.106 2025-06-09 22:22:22.120896 :A2908D35:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12820 pso_num=27 pso_serial#=79 2025-06-09 22:22:22.121036 :A2908D36:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12820 (legacy spawn) 2025-06-09 22:22:22.127594 :A2908D37: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-06-09 22:22:22.127758 :A2908D40: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-06-09 22:22:22.128595 :A2908D41:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:23:08.110394 :A290D769:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:23:08.106 2025-06-09 22:23:08.110396 :A290D76A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16276 pso_num=27 pso_serial#=80 2025-06-09 22:23:08.110564 :A290D76B:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16276 osp_idx=25 osp_ver=55669 osp_pg=0 (spawn #269214) 2025-06-09 22:23:08.110565 :A290D76C:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 22:23:08.110565 :A290D76D:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=9 time=2483531282 2025-06-09 22:23:08.110565 :A290D76E:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 22:23:08.110566 :A290D76F:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 22:23:08.110566 :A290D770:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 22:23:08.110567 :A290D771:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 22:23:08.110567 :A290D772:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 22:23:08.111441 :A290D773: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-06-09 22:23:08.111486 :A290D779: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-06-09 22:23:08.209276 :A290D7AB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-09 22:23:09.415451 :A290D7EB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-09 22:23:09.402 2025-06-09 22:23:09.415453 :A290D7EC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14460 pso_num=27 pso_serial#=81 2025-06-09 22:23:09.415580 :A290D7ED:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14460 osp_idx=25 osp_ver=55670 osp_pg=0 (spawn #269216) 2025-06-09 22:23:09.415581 :A290D7EE:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 22:23:09.415582 :A290D7EF:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=10 time=2483532594 2025-06-09 22:23:09.415582 :A290D7F0:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 22:23:09.415582 :A290D7F1:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 22:23:09.415583 :A290D7F2:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 22:23:09.415583 :A290D7F3:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 22:23:09.415584 :A290D7F4:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 22:23:09.416070 :A290D7F5: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-06-09 22:23:09.416111 :A290D7FB: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: 14460, M002)