Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250809100532\orcl_w000_6512_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:35639M/63366M, Ph+PgF:41143M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 6512, image: ORACLE.EXE (W000) *** 2025-08-09 10:05:32.626 *** SESSION ID:(177.1723) 2025-08-09 10:05:32.626 *** 2025-08-09 10:05:32.626 Process diagnostic dump for ORACLE.EXE (W000), OS id=6512, pid: 22, proc_ser: 225, sid: 177, sess_ser: 1723 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=181 seq_num=182 snap_id=1 wait times: snap=3.307808 sec, exc=3.307808 sec, total=3.307808 sec wait times: max=5.000000 sec, heur=14 min 35 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=180 seq_num=181 snap_id=1 wait times: snap=5.000176 sec, exc=5.000176 sec, total=5.000176 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=179 seq_num=180 snap_id=1 wait times: snap=5.011511 sec, exc=5.011511 sec, total=5.011511 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=178 seq_num=179 snap_id=1 wait times: snap=5.010128 sec, exc=5.010128 sec, total=5.010128 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=177 seq_num=178 snap_id=1 wait times: snap=5.004624 sec, exc=5.004624 sec, total=5.004624 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=176 seq_num=177 snap_id=1 wait times: snap=5.004594 sec, exc=5.004594 sec, total=5.004594 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000076 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=175 seq_num=176 snap_id=1 wait times: snap=5.014733 sec, exc=5.014733 sec, total=5.014733 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=174 seq_num=175 snap_id=1 wait times: snap=5.015198 sec, exc=5.015198 sec, total=5.015198 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000093 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=173 seq_num=174 snap_id=1 wait times: snap=5.003408 sec, exc=5.003408 sec, total=5.003408 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=172 seq_num=173 snap_id=1 wait times: snap=5.003317 sec, exc=5.003317 sec, total=5.003317 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=171 seq_num=172 snap_id=1 wait times: snap=5.009390 sec, exc=5.009390 sec, total=5.009390 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time Sampled Session History of session 177 serial 1723 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 10:03:32 - 10:05:32] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-09 10:05:32.626 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 6512, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-09 09:08:41.184718 :BD5D734D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:08:41.170 2025-08-09 09:08:41.184720 :BD5D734E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15516 pso_num=22 pso_serial#=208 2025-08-09 09:08:41.184857 :BD5D734F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15516 (legacy spawn) 2025-08-09 09:08:41.191290 :BD5D7350:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:08:41.191463 :BD5D7355:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:08:41.192249 :BD5D735A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:08:41.200911 :BD5D735D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:08:41.186 2025-08-09 09:08:41.200912 :BD5D735E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7320 pso_num=22 pso_serial#=209 2025-08-09 09:08:41.201043 :BD5D735F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7320 (legacy spawn) 2025-08-09 09:08:41.207532 :BD5D7360:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:08:41.207696 :BD5D7369:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:08:41.208489 :BD5D736A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:08:44.434387 :BD5D748E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:08:44.421 2025-08-09 09:08:44.434390 :BD5D748F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19440 pso_num=22 pso_serial#=210 2025-08-09 09:08:44.434525 :BD5D7490:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19440 (legacy spawn) 2025-08-09 09:08:44.454491 :BD5D7491:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:08:44.454666 :BD5D7496:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:08:44.455553 :BD5D749B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:08:44.464187 :BD5D749E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:08:44.452 2025-08-09 09:08:44.464189 :BD5D749F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=208 pso_num=22 pso_serial#=211 2025-08-09 09:08:44.464324 :BD5D74A0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=208 (legacy spawn) 2025-08-09 09:08:44.470814 :BD5D74A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:08:44.470970 :BD5D74AA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:08:44.471766 :BD5D74AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:01.731717 :BD5D79E2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:01.718 2025-08-09 09:09:01.731719 :BD5D79E3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20652 pso_num=22 pso_serial#=212 2025-08-09 09:09:01.731853 :BD5D79E4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20652 (legacy spawn) 2025-08-09 09:09:01.738410 :BD5D79E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:01.738445 :BD5D79E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:01.738606 :BD5D79EF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:01.739439 :BD5D79FC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:02.067616 :BD5D7A23:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:02.062 2025-08-09 09:09:02.067618 :BD5D7A24:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20468 pso_num=22 pso_serial#=213 2025-08-09 09:09:02.067755 :BD5D7A25:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20468 osp_idx=0 osp_ver=497280 osp_pg=0 (spawn #470707) 2025-08-09 09:09:02.067756 :BD5D7A26:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:09:02.067756 :BD5D7A27:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=237 time=3411211376 2025-08-09 09:09:02.067757 :BD5D7A28:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:09:02.067757 :BD5D7A29:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:09:02.067757 :BD5D7A2A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:09:02.067758 :BD5D7A2B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:09:02.067758 :BD5D7A2C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:09:02.068239 :BD5D7A2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:09:02.068877 :BD5D7A42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:02.076410 :BD5D7A5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-09 09:09:02.076796 :BD5D7A5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:02.076832 :BD5D7A62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-09 09:09:02.077669 :BD5D7A68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:02.077790 :BD5D7A71:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:32.144692 :BD5D842F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:35.935881 :BD5D856E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:35.927 2025-08-09 09:09:35.935883 :BD5D856F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18400 pso_num=22 pso_serial#=214 2025-08-09 09:09:35.936020 :BD5D8570:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18400 (legacy spawn) 2025-08-09 09:09:35.942461 :BD5D8571:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:35.942630 :BD5D8576:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:35.943405 :BD5D857B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:41.470858 :BD5D871E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:41.462 2025-08-09 09:09:41.470860 :BD5D871F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20836 pso_num=22 pso_serial#=215 2025-08-09 09:09:41.470990 :BD5D8720:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20836 (legacy spawn) 2025-08-09 09:09:41.477409 :BD5D8721:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:41.477582 :BD5D8726:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:41.478426 :BD5D872B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:54.286911 :BD5D8B42:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:54.284 2025-08-09 09:09:54.286913 :BD5D8B43:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10444 pso_num=22 pso_serial#=216 2025-08-09 09:09:54.287053 :BD5D8B44:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10444 (legacy spawn) 2025-08-09 09:09:54.293486 :BD5D8B45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:54.293682 :BD5D8B4A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:54.294491 :BD5D8B4F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:54.303240 :BD5D8B52:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:54.299 2025-08-09 09:09:54.303242 :BD5D8B53:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21432 pso_num=22 pso_serial#=217 2025-08-09 09:09:54.303375 :BD5D8B54:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21432 (legacy spawn) 2025-08-09 09:09:54.309884 :BD5D8B55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:54.310050 :BD5D8B5E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:54.310835 :BD5D8B5F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:57.487131 :BD5D8C5D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:57.473 2025-08-09 09:09:57.487133 :BD5D8C5E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20716 pso_num=22 pso_serial#=218 2025-08-09 09:09:57.487271 :BD5D8C5F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20716 (legacy spawn) 2025-08-09 09:09:57.507112 :BD5D8C60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:57.507288 :BD5D8C65:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:57.508173 :BD5D8C6A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:09:57.516764 :BD5D8C6D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:09:57.505 2025-08-09 09:09:57.516767 :BD5D8C6E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16476 pso_num=22 pso_serial#=219 2025-08-09 09:09:57.516904 :BD5D8C6F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16476 (legacy spawn) 2025-08-09 09:09:57.523470 :BD5D8C70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:09:57.523629 :BD5D8C79:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-09 09:09:57.524420 :BD5D8C7A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:10:02.124947 :BD5D8DC8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:10:02.119 2025-08-09 09:10:02.124950 :BD5D8DC9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7572 pso_num=22 pso_serial#=220 2025-08-09 09:10:02.125123 :BD5D8DCA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7572 osp_idx=0 osp_ver=497287 osp_pg=0 (spawn #470709) 2025-08-09 09:10:02.125124 :BD5D8DCB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:10:02.125124 :BD5D8DCC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=238 time=3411271423 2025-08-09 09:10:02.125125 :BD5D8DCD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:10:02.125125 :BD5D8DCE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:10:02.125125 :BD5D8DCF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:10:02.125126 :BD5D8DD0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:10:02.125126 :BD5D8DD1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:10:02.125625 :BD5D8DD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:10:02.126264 :BD5D8DE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:10:02.140455 :BD5D8E03:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-09 09:10:02.140538 :BD5D8E04:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-09 09:10:02.141425 :BD5D8E05:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-09 09:10:02.141761 :BD5D8E06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:10:02.141801 :BD5D8E09:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-09 09:10:02.149710 :BD5D8E0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:10:02.149774 :BD5D8E12:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-08-09 09:10:02.149811 :BD5D8E13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-08-09 09:10:02.150198 :BD5D8E19:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-09 09:10:02.150546 :BD5D8E1A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-09 09:10:02.150743 :BD5D8E1B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-09 09:10:02.150973 :BD5D8E1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:10:02.150999 :BD5D8E1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-09 09:10:02.152557 :BD5D8E25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-09 09:10:02.152777 :BD5D8E26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:10:02.152806 :BD5D8E29:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-09 09:10:02.153737 :BD5D8E2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 09:10:32.322018 :BD5D9781:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:10:32.454179 :BD5D97B7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:10:32.449 2025-08-09 09:10:32.454181 :BD5D97B8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19036 pso_num=22 pso_serial#=221 2025-08-09 09:10:32.454318 :BD5D97B9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19036 osp_idx=0 osp_ver=497288 osp_pg=0 (spawn #470711) 2025-08-09 09:10:32.454318 :BD5D97BA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:10:32.454319 :BD5D97BB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=239 time=3411301767 2025-08-09 09:10:32.454319 :BD5D97BC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:10:32.454319 :BD5D97BD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:10:32.454320 :BD5D97BE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:10:32.454320 :BD5D97BF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:10:32.454320 :BD5D97C0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:10:32.454824 :BD5D97C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:10:32.454868 :BD5D97C7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-09 09:20:33.257094 :BD5E5A3D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:20:33.808754 :BD5E5A76:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:20:33.804 2025-08-09 09:20:33.808756 :BD5E5A77:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18620 pso_num=22 pso_serial#=222 2025-08-09 09:20:33.808898 :BD5E5A78:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18620 osp_idx=0 osp_ver=497289 osp_pg=0 (spawn #470734) 2025-08-09 09:20:33.808898 :BD5E5A79:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:20:33.808899 :BD5E5A7A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=240 time=3411903111 2025-08-09 09:20:33.808899 :BD5E5A7B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:20:33.808899 :BD5E5A7C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:20:33.808900 :BD5E5A7D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:20:33.808900 :BD5E5A7E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:20:33.808900 :BD5E5A7F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:20:33.809389 :BD5E5A80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:20:33.809436 :BD5E5A88:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-09 09:30:34.615073 :BD5F1EAD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:30:35.057357 :BD5F1EE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:30:35.052 2025-08-09 09:30:35.057359 :BD5F1EE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20984 pso_num=22 pso_serial#=223 2025-08-09 09:30:35.057503 :BD5F1EE2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20984 osp_idx=0 osp_ver=497290 osp_pg=0 (spawn #470756) 2025-08-09 09:30:35.057504 :BD5F1EE3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:30:35.057504 :BD5F1EE4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=241 time=3412504361 2025-08-09 09:30:35.057505 :BD5F1EE5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:30:35.057505 :BD5F1EE6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:30:35.057506 :BD5F1EE7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:30:35.057506 :BD5F1EE8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:30:35.057507 :BD5F1EE9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:30:35.058007 :BD5F1EEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:30:35.058052 :BD5F1EF1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-09 09:40:35.875363 :BD5FE228:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:40:36.312109 :BD5FE26F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:40:36.307 2025-08-09 09:40:36.312112 :BD5FE270:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15576 pso_num=22 pso_serial#=224 2025-08-09 09:40:36.312251 :BD5FE271:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15576 osp_idx=0 osp_ver=497291 osp_pg=0 (spawn #470779) 2025-08-09 09:40:36.312252 :BD5FE272:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:40:36.312252 :BD5FE273:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=242 time=3413105611 2025-08-09 09:40:36.312253 :BD5FE274:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:40:36.312253 :BD5FE275:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:40:36.312253 :BD5FE276:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:40:36.312254 :BD5FE277:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:40:36.312254 :BD5FE278:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:40:36.312749 :BD5FE279:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:40:36.312795 :BD5FE280:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-09 09:50:37.138739 :BD60A443:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 09:50:37.627916 :BD60A47C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 09:50:37.623 2025-08-09 09:50:37.627919 :BD60A47D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6512 pso_num=22 pso_serial#=225 2025-08-09 09:50:37.628076 :BD60A47E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6512 osp_idx=0 osp_ver=497292 osp_pg=0 (spawn #470802) 2025-08-09 09:50:37.628077 :BD60A47F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 09:50:37.628077 :BD60A480:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=243 time=3413706939 2025-08-09 09:50:37.628078 :BD60A481:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 09:50:37.628078 :BD60A482:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 09:50:37.628079 :BD60A483:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 09:50:37.628079 :BD60A484:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 09:50:37.628079 :BD60A485:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 09:50:37.628573 :BD60A486:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 09:50:37.628618 :BD60A48D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-09 09:50:57.557588 :BD60AACF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-09 10:00:38.953012 :BD616976:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 6512, W000)