Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250505082112\orcl_m002_11792_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:37547M/63366M, Ph+PgF:45010M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 11792, image: ORACLE.EXE (M002) *** 2025-05-05 08:21:12.822 *** SESSION ID:(193.223) 2025-05-05 08:21:12.822 *** 2025-05-05 08:21:12.822 Process diagnostic dump for ORACLE.EXE (M002), OS id=11792, pid: 24, proc_ser: 182, sid: 193, sess_ser: 223 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x25a2, blocks=0x1 wait_id=1477 seq_num=1478 snap_id=1 wait times: snap=0.000552 sec, exc=0.000552 sec, total=0.000552 sec wait times: max=infinite, heur=0.000552 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000135 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x258a, blocks=0x1 wait_id=1476 seq_num=1477 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.000133 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x2572, blocks=0x1 wait_id=1475 seq_num=1476 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.000149 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x245a, blocks=0x1 wait_id=1474 seq_num=1475 snap_id=1 wait times: snap=0.000087 sec, exc=0.000087 sec, total=0.000087 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x25da, blocks=0x1 wait_id=1473 seq_num=1474 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.000293 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x2562, blocks=0x1 wait_id=1472 seq_num=1473 snap_id=1 wait times: snap=0.000171 sec, exc=0.000171 sec, total=0.000171 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000248 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x254a, blocks=0x1 wait_id=1471 seq_num=1472 snap_id=1 wait times: snap=0.000841 sec, exc=0.000841 sec, total=0.000841 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000370 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x2532, blocks=0x1 wait_id=1470 seq_num=1471 snap_id=1 wait times: snap=0.004100 sec, exc=0.004100 sec, total=0.004100 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000252 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x251a, blocks=0x1 wait_id=1469 seq_num=1470 snap_id=1 wait times: snap=0.001803 sec, exc=0.001803 sec, total=0.001803 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000141 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x2502, blocks=0x1 wait_id=1468 seq_num=1469 snap_id=1 wait times: snap=0.000251 sec, exc=0.000251 sec, total=0.000251 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000146 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x24fa, blocks=0x1 wait_id=1467 seq_num=1468 snap_id=1 wait times: snap=0.000337 sec, exc=0.000337 sec, total=0.000337 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000162 sec of elapsed time Sampled Session History of session 193 serial 223 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [1 sample, 08:21:12] waited for 'db file sequential read', seq_num: 1430 p1: 'file#'=0x2 p2: 'block#'=0x1dd2 p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:21:11] idle wait at each sample [session created at: 08:21:12] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:21:12 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-05 08:21:12.822 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 11792, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-05 08:08:20.226846 :92EEE787:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-05 08:08:20.227364 :92EEE788:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-05 08:08:20.229672 :92EEE789:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-05 08:08:20.230015 :92EEE78A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 08:08:20.230050 :92EEE78D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-05 08:08:20.230950 :92EEE793:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 08:08:20.231092 :92EEE79C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-05 08:08:50.340016 :92EEF145:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:09:20.316045 :92EEFB49:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:09:20.305 2025-05-05 08:09:20.316047 :92EEFB4A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11632 pso_num=24 pso_serial#=167 2025-05-05 08:09:20.316182 :92EEFB4B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11632 osp_idx=22 osp_ver=226861 osp_pg=0 (spawn #150173) 2025-05-05 08:09:20.316182 :92EEFB4C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:09:20.316183 :92EEFB4D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=12 time=3703337468 2025-05-05 08:09:20.316183 :92EEFB4E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:09:20.316184 :92EEFB4F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:09:20.316184 :92EEFB50:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:09:20.316185 :92EEFB51:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:09:20.316185 :92EEFB52:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:09:20.316663 :92EEFB53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:09:50.437566 :92EF0506:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:10:20.402427 :92EF11A5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:10:20.391 2025-05-05 08:10:20.402429 :92EF11A6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11312 pso_num=24 pso_serial#=168 2025-05-05 08:10:20.402568 :92EF11A7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11312 osp_idx=22 osp_ver=226862 osp_pg=0 (spawn #150175) 2025-05-05 08:10:20.402569 :92EF11A8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:10:20.402570 :92EF11A9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=13 time=3703397562 2025-05-05 08:10:20.402571 :92EF11AA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:10:20.402571 :92EF11AB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:10:20.402572 :92EF11AC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:10:20.402572 :92EF11AD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:10:20.402573 :92EF11AE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:10:20.403044 :92EF11AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:10:50.479049 :92EF1BBA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:11:20.492466 :92EF259F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:11:20.481 2025-05-05 08:11:20.492468 :92EF25A0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11988 pso_num=24 pso_serial#=169 2025-05-05 08:11:20.492604 :92EF25A1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11988 osp_idx=22 osp_ver=226863 osp_pg=0 (spawn #150177) 2025-05-05 08:11:20.492604 :92EF25A2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:11:20.492605 :92EF25A3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=14 time=3703457656 2025-05-05 08:11:20.492605 :92EF25A4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:11:20.492605 :92EF25A5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:11:20.492606 :92EF25A6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:11:20.492606 :92EF25A7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:11:20.492606 :92EF25A8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:11:20.493069 :92EF25A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:11:50.580622 :92EF2F57:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:12:20.588717 :92EF3960:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:12:20.577 2025-05-05 08:12:20.588719 :92EF3961:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11536 pso_num=24 pso_serial#=170 2025-05-05 08:12:20.588851 :92EF3962:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11536 osp_idx=22 osp_ver=226864 osp_pg=0 (spawn #150179) 2025-05-05 08:12:20.588851 :92EF3963:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:12:20.588852 :92EF3964:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=15 time=3703517750 2025-05-05 08:12:20.588852 :92EF3965:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:12:20.588852 :92EF3966:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:12:20.588853 :92EF3967:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:12:20.588854 :92EF3968:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:12:20.588854 :92EF3969:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:12:20.589328 :92EF396A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:12:50.693535 :92EF43D5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:12:50.745676 :92EF43D8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:12:50.741 2025-05-05 08:12:50.745678 :92EF43D9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11320 pso_num=24 pso_serial#=171 2025-05-05 08:12:50.745813 :92EF43DA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11320 (legacy spawn) 2025-05-05 08:12:50.752213 :92EF43DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 08:12:50.752391 :92EF43E1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-05 08:12:50.753163 :92EF43E5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:12:50.761863 :92EF43E8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:12:50.756 2025-05-05 08:12:50.761865 :92EF43E9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10440 pso_num=24 pso_serial#=172 2025-05-05 08:12:50.762000 :92EF43EA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10440 (legacy spawn) 2025-05-05 08:12:50.768463 :92EF43EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 08:12:50.768628 :92EF43F3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-05 08:12:50.769486 :92EF43F5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:13:20.702451 :92EF4DD0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:13:20.691 2025-05-05 08:13:20.702453 :92EF4DD1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7552 pso_num=24 pso_serial#=173 2025-05-05 08:13:20.702582 :92EF4DD2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7552 osp_idx=22 osp_ver=226867 osp_pg=0 (spawn #150181) 2025-05-05 08:13:20.702583 :92EF4DD3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:13:20.702583 :92EF4DD4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=16 time=3703577859 2025-05-05 08:13:20.702584 :92EF4DD5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:13:20.702584 :92EF4DD6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:13:20.702584 :92EF4DD7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:13:20.702584 :92EF4DD8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:13:20.702585 :92EF4DD9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:13:20.703117 :92EF4DDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:13:50.798310 :92EF579E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:14:20.793523 :92EF61B1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:14:20.782 2025-05-05 08:14:20.793525 :92EF61B2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10896 pso_num=24 pso_serial#=174 2025-05-05 08:14:20.793659 :92EF61B3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10896 osp_idx=22 osp_ver=226868 osp_pg=0 (spawn #150184) 2025-05-05 08:14:20.793659 :92EF61B4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:14:20.793660 :92EF61B5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=17 time=3703637953 2025-05-05 08:14:20.793660 :92EF61B6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:14:20.793661 :92EF61B7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:14:20.793661 :92EF61B8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:14:20.793661 :92EF61B9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:14:20.793662 :92EF61BA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:14:20.794161 :92EF61BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:14:50.895788 :92EF6B7B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:15:20.897735 :92EF758B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:15:20.886 2025-05-05 08:15:20.897737 :92EF758C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9496 pso_num=24 pso_serial#=175 2025-05-05 08:15:20.897861 :92EF758D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9496 osp_idx=22 osp_ver=226869 osp_pg=0 (spawn #150186) 2025-05-05 08:15:20.897862 :92EF758E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:15:20.897863 :92EF758F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=18 time=3703698062 2025-05-05 08:15:20.897863 :92EF7590:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:15:20.897863 :92EF7591:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:15:20.897864 :92EF7592:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:15:20.897864 :92EF7593:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:15:20.897865 :92EF7594:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:15:20.898358 :92EF7595:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:15:50.972874 :92EF7FC3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:16:20.996938 :92EF89C1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:16:20.985 2025-05-05 08:16:20.996940 :92EF89C2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7276 pso_num=24 pso_serial#=176 2025-05-05 08:16:20.997112 :92EF89C3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7276 osp_idx=22 osp_ver=226870 osp_pg=0 (spawn #150188) 2025-05-05 08:16:20.997112 :92EF89C4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:16:20.997113 :92EF89C5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=19 time=3703758156 2025-05-05 08:16:20.997113 :92EF89C6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:16:20.997113 :92EF89C7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:16:20.997114 :92EF89C8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:16:20.997114 :92EF89C9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:16:20.997114 :92EF89CA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:16:20.997624 :92EF89CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:16:51.049285 :92EF938F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:17:21.069572 :92EF9D67:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:17:21.058 2025-05-05 08:17:21.069575 :92EF9D68:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11348 pso_num=24 pso_serial#=177 2025-05-05 08:17:21.069705 :92EF9D69:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11348 osp_idx=22 osp_ver=226871 osp_pg=0 (spawn #150190) 2025-05-05 08:17:21.069705 :92EF9D6A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:17:21.069706 :92EF9D6B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=20 time=3703818234 2025-05-05 08:17:21.069706 :92EF9D6C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:17:21.069707 :92EF9D6D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:17:21.069707 :92EF9D6E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:17:21.069707 :92EF9D6F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:17:21.069707 :92EF9D70:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:17:21.070222 :92EF9D71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:17:51.178991 :92EFA7A5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:18:16.201950 :92EFAF65:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:18:16.190 2025-05-05 08:18:16.201952 :92EFAF66:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9672 pso_num=24 pso_serial#=178 2025-05-05 08:18:16.202095 :92EFAF67:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9672 osp_idx=22 osp_ver=226872 osp_pg=0 (spawn #150193) 2025-05-05 08:18:16.202096 :92EFAF68:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:18:16.202096 :92EFAF69:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=21 time=3703873359 2025-05-05 08:18:16.202096 :92EFAF6A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:18:16.202097 :92EFAF6B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:18:16.202097 :92EFAF6C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:18:16.202098 :92EFAF6D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:18:16.202098 :92EFAF6E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:18:16.202611 :92EFAF6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:18:21.193739 :92EFB172:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-05 08:18:51.300281 :92EFBB2D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:19:21.328409 :92EFC532:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:19:21.317 2025-05-05 08:19:21.328412 :92EFC533:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9868 pso_num=24 pso_serial#=179 2025-05-05 08:19:21.328549 :92EFC534:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9868 osp_idx=22 osp_ver=226873 osp_pg=0 (spawn #150196) 2025-05-05 08:19:21.328550 :92EFC535:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:19:21.328550 :92EFC536:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=22 time=3703938484 2025-05-05 08:19:21.328550 :92EFC537:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:19:21.328551 :92EFC538:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:19:21.328551 :92EFC539:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:19:21.328551 :92EFC53A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:19:21.328552 :92EFC53B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:19:21.329018 :92EFC53C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:19:51.417273 :92EFCEEA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:20:21.430692 :92EFD8CE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:20:21.419 2025-05-05 08:20:21.430694 :92EFD8CF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11284 pso_num=24 pso_serial#=180 2025-05-05 08:20:21.430830 :92EFD8D0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11284 osp_idx=22 osp_ver=226874 osp_pg=0 (spawn #150198) 2025-05-05 08:20:21.430830 :92EFD8D1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:20:21.430831 :92EFD8D2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=23 time=3703998593 2025-05-05 08:20:21.430831 :92EFD8D3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:20:21.430831 :92EFD8D4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:20:21.430832 :92EFD8D5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:20:21.430832 :92EFD8D6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:20:21.430832 :92EFD8D7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:20:21.431303 :92EFD8D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:20:51.520744 :92EFE2FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:21:10.017971 :92F02287:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:21:10.002 2025-05-05 08:21:10.017973 :92F02288:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3352 pso_num=24 pso_serial#=181 2025-05-05 08:21:10.018152 :92F02289:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3352 osp_idx=22 osp_ver=226875 osp_pg=0 (spawn #150200) 2025-05-05 08:21:10.018153 :92F0228A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:21:10.018153 :92F0228B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=3704047171 2025-05-05 08:21:10.018154 :92F0228C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:21:10.018155 :92F0228D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:21:10.018155 :92F0228E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:21:10.018156 :92F0228F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:21:10.018156 :92F02290:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:21:10.018954 :92F02291:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:21:10.018997 :92F02297:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-05 08:21:10.127193 :92F022C9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-05 08:21:12.220173 :92F023AD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-05 08:21:12.212 2025-05-05 08:21:12.220175 :92F023AE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11792 pso_num=24 pso_serial#=182 2025-05-05 08:21:12.220305 :92F023AF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11792 osp_idx=22 osp_ver=226876 osp_pg=0 (spawn #150202) 2025-05-05 08:21:12.220306 :92F023B0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 08:21:12.220306 :92F023B1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=25 time=3704049390 2025-05-05 08:21:12.220306 :92F023B2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 08:21:12.220307 :92F023B3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 08:21:12.220307 :92F023B4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 08:21:12.220307 :92F023B5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 08:21:12.220308 :92F023B6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 08:21:12.220810 :92F023B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 08:21:12.220853 :92F023BE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 11792, M002)