Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250808220005\orcl_j001_20188_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:35644M/63366M, Ph+PgF:41142M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 20188, image: ORACLE.EXE (J001) *** 2025-08-08 22:00:06.087 *** SESSION ID:(219.45199) 2025-08-08 22:00:06.087 *** 2025-08-08 22:00:06.087 Process diagnostic dump for ORACLE.EXE (J001), OS id=20188, pid: 27, proc_ser: 96, sid: 219, sess_ser: 45199 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20188 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.075567 sec, exc=0.075567 sec, total=0.075567 sec wait times: max=0.500000 sec, heur=1.570768 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.000015 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.495176 sec, exc=0.495176 sec, total=0.495176 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 219 serial 45199 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-08 22:00:06.087 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 20188, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-08 21:37:19.871541 :BD27E088:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20336 (legacy spawn) 2025-08-08 21:37:19.878163 :BD27E089: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-08-08 21:37:19.878346 :BD27E08E: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-08-08 21:37:19.879172 :BD27E093:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:37:19.898575 :BD27E096:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:37:19.889 2025-08-08 21:37:19.898577 :BD27E097:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19572 pso_num=27 pso_serial#=82 2025-08-08 21:37:19.898712 :BD27E098:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19572 (legacy spawn) 2025-08-08 21:37:19.905872 :BD27E099: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-08-08 21:37:19.906054 :BD27E0A2: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-08-08 21:37:19.906964 :BD27E0A3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:37:20.272335 :BD27E0A6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:37:20.264 2025-08-08 21:37:20.272337 :BD27E0A7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21076 pso_num=27 pso_serial#=83 2025-08-08 21:37:20.272471 :BD27E0A8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21076 (legacy spawn) 2025-08-08 21:37:20.278846 :BD27E0A9: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-08-08 21:37:20.279020 :BD27E0B2: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-08-08 21:37:20.279828 :BD27E0B3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:37:25.481454 :BD27E268:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:37:25.468 2025-08-08 21:37:25.481457 :BD27E269:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17316 pso_num=27 pso_serial#=84 2025-08-08 21:37:25.481591 :BD27E26A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17316 (legacy spawn) 2025-08-08 21:37:25.488086 :BD27E26B: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-08-08 21:37:25.488254 :BD27E270: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-08-08 21:37:25.489093 :BD27E275:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:37:25.497717 :BD27E278:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:37:25.484 2025-08-08 21:37:25.497719 :BD27E279:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19564 pso_num=27 pso_serial#=85 2025-08-08 21:37:25.497859 :BD27E27A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19564 (legacy spawn) 2025-08-08 21:37:25.504353 :BD27E27B: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-08-08 21:37:25.504528 :BD27E283: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-08-08 21:37:25.505394 :BD27E285:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:38:03.863720 :BD27EF20:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:38:03.855 2025-08-08 21:38:03.863721 :BD27EF21:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20976 pso_num=27 pso_serial#=86 2025-08-08 21:38:03.863857 :BD27EF22:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20976 (legacy spawn) 2025-08-08 21:38:03.870374 :BD27EF23: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-08-08 21:38:03.870547 :BD27EF29: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-08-08 21:38:03.871374 :BD27EF2D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:38:19.011098 :BD27F395:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:38:19.001 2025-08-08 21:38:19.011100 :BD27F396:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17888 pso_num=27 pso_serial#=87 2025-08-08 21:38:19.011236 :BD27F397:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17888 (legacy spawn) 2025-08-08 21:38:19.017658 :BD27F398: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-08-08 21:38:19.017827 :BD27F3A1: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-08-08 21:38:19.018601 :BD27F3A2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:38:22.435777 :BD27F486:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:38:22.427 2025-08-08 21:38:22.435779 :BD27F487:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19616 pso_num=27 pso_serial#=88 2025-08-08 21:38:22.435919 :BD27F488:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19616 (legacy spawn) 2025-08-08 21:38:22.442374 :BD27F489: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-08-08 21:38:22.442541 :BD27F48E: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-08-08 21:38:22.443347 :BD27F493:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:38:29.384018 :BD27F67B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:38:29.384 2025-08-08 21:38:29.384020 :BD27F67C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15328 pso_num=27 pso_serial#=89 2025-08-08 21:38:29.384175 :BD27F67D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15328 (legacy spawn) 2025-08-08 21:38:29.391131 :BD27F67E: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-08-08 21:38:29.391337 :BD27F683: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-08-08 21:38:29.392254 :BD27F688:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:38:29.401650 :BD27F68B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:38:29.399 2025-08-08 21:38:29.401652 :BD27F68C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19036 pso_num=27 pso_serial#=90 2025-08-08 21:38:29.401793 :BD27F68D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19036 (legacy spawn) 2025-08-08 21:38:29.417191 :BD27F68E: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-08-08 21:38:29.417413 :BD27F697: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-08-08 21:38:29.418406 :BD27F698:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:39:05.787611 :BD28021E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:39:05.780 2025-08-08 21:39:05.787613 :BD28021F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9904 pso_num=27 pso_serial#=91 2025-08-08 21:39:05.787755 :BD280220:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9904 (legacy spawn) 2025-08-08 21:39:05.794373 :BD280221: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-08-08 21:39:05.794565 :BD280226: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-08-08 21:39:05.795522 :BD28022B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:39:05.804503 :BD28022E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:39:05.796 2025-08-08 21:39:05.804505 :BD28022F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20328 pso_num=27 pso_serial#=92 2025-08-08 21:39:05.804651 :BD280230:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20328 (legacy spawn) 2025-08-08 21:39:05.811344 :BD280231: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-08-08 21:39:05.811511 :BD28023A: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-08-08 21:39:05.812352 :BD28023B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:39:08.976810 :BD28038D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:39:08.972 2025-08-08 21:39:08.976811 :BD28038E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15644 pso_num=27 pso_serial#=93 2025-08-08 21:39:08.976956 :BD28038F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15644 osp_idx=25 osp_ver=251786 osp_pg=0 (spawn #469098) 2025-08-08 21:39:08.976957 :BD280390:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-08 21:39:08.976958 :BD280391:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=90 time=3369818283 2025-08-08 21:39:08.976958 :BD280392:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 21:39:08.976959 :BD280393:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 21:39:08.976959 :BD280394:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 21:39:08.976960 :BD280395:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 21:39:08.976960 :BD280396:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 21:39:08.977426 :BD28039A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-08 21:39:08.977465 :BD2803A0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 21:40:28.420503 :BD281E31:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08 21:49:10.243562 :BD28C645:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-08 21:59:11.045192 :BD2986F0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:59:20.423494 :BD2989E1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:59:20.414 2025-08-08 21:59:20.423496 :BD2989E2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20804 pso_num=27 pso_serial#=94 2025-08-08 21:59:20.423631 :BD2989E3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20804 (legacy spawn) 2025-08-08 21:59:20.430111 :BD2989E4: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-08-08 21:59:20.430278 :BD2989ED: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-08-08 21:59:20.431109 :BD2989EE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 21:59:24.004814 :BD298B47:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 21:59:23.996 2025-08-08 21:59:24.004816 :BD298B48:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20152 pso_num=27 pso_serial#=95 2025-08-08 21:59:24.004959 :BD298B49:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20152 (legacy spawn) 2025-08-08 21:59:24.011374 :BD298B4A: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-08-08 21:59:24.011554 :BD298B53: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-08-08 21:59:24.012428 :BD298B54:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-08 22:00:02.035730 :BD2998A0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-08 22:00:02.024 2025-08-08 22:00:02.035732 :BD2998A1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20188 pso_num=27 pso_serial#=96 2025-08-08 22:00:02.035870 :BD2998A2:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=20188 osp_idx=25 osp_ver=251789 osp_pg=0 (spawn #469146) 2025-08-08 22:00:02.035871 :BD2998A3:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-08 22:00:02.035872 :BD2998A4:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=91 time=3371071329 2025-08-08 22:00:02.035872 :BD2998A5:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 22:00:02.035873 :BD2998A6:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 22:00:02.035873 :BD2998A7:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 22:00:02.035873 :BD2998A8:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 22:00:02.035874 :BD2998A9:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 22:00:02.036368 :BD2998AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-08 22:00:02.041446 :BD2998D2: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-08-08 22:00:02.041495 :BD2998D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-08-08 22:00:02.044926 :BD2998F0: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-08-08 22:00:02.429800 :BD299C1F: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-08-08 22:00:02.473589 :BD299C27: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-08-08 22:00:02.489369 :BD299C2F: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-08-08 22:00:02.547002 :BD299C7E: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-08-08 22:00:02.679437 :BD299CB1: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-08-08 22:00:02.794399 :BD299CB9: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-08-08 22:00:02.804189 :BD299CC1: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-08-08 22:00:02.819853 :BD299CC9: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-08-08 22:00:02.835249 :BD299CD1: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-08-08 22:00:02.851198 :BD299CD9: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-08-08 22:00:02.860854 :BD299CE1: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-08-08 22:00:02.876478 :BD299CE9: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-08-08 22:00:02.886116 :BD299CF1: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-08-08 22:00:02.901908 :BD299CF9: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-08-08 22:00:02.911855 :BD299D01: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-08-08 22:00:02.921484 :BD299D09: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-08-08 22:00:02.936995 :BD299D11: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-08-08 22:00:02.947131 :BD299D19: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-08-08 22:00:02.963614 :BD299D21: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-08-08 22:00:02.979869 :BD299D29: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-08-08 22:00:02.995976 :BD299D3D: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-08-08 22:00:03.012129 :BD299D6D: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-08-08 22:00:03.022333 :BD299D75: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-08-08 22:00:03.038524 :BD299D7D: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-08-08 22:00:03.048940 :BD299D85: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-08-08 22:00:03.058578 :BD299D8D: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-08-08 22:00:03.074658 :BD299D95: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-08-08 22:00:03.083687 :BD299D9D: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-08-08 22:00:03.092928 :BD299DA5: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-08-08 22:00:03.108335 :BD299DAD: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-08-08 22:00:03.115486 :BD299DB5: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-08-08 22:00:03.131196 :BD299DBD: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-08-08 22:00:03.146662 :BD299DC5: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-08-08 22:00:03.149319*:BD299DD7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000055390388 estart=1754661602 eid=16806128 ctx=0x0000000783637648 cwh=0x000000002B510068 cpu=15625 buffg=172 interb=0 r=0 rb=0 w=0 wb=0 2025-08-08 22:00:03.149319*:BD299DD8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000055390388 curCtx=0x00000000559DFEB8 pubTabIdxCnt=0 2025-08-08 22:00:03.149319*:BD299DD9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000055390388 2025-08-08 22:00:03.149319*:BD299DDA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000055390388 2025-08-08 22:00:03.149319*:BD299DDB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028FFC288 estart=1754661601 eid=16777353 ctx=0x000000079F654AA8 cwh=0x0000000028FF0158 cpu=1093750 buffg=78141 interb=17809408 r=808 rb=15499264 w=12 wb=2310144 2025-08-08 22:00:03.149319*:BD299DDC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028FFC288 curCtx=0x00000000559DEFE0 pubTabIdxCnt=0 2025-08-08 22:00:03.149319*:BD299DDD:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028FFC288 2025-08-08 22:00:03.149319*:BD299DDE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028FFC288 2025-08-08 22:00:03.149319*:BD299DDF:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000055390388 curCtx=0x00000000559DFEB8 xsc->flg4=65792 flags=0 2025-08-08 22:00:03.149319*:BD299DE0:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000055390388 2025-08-08 22:00:03.156837 :BD299DE1: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-08-08 22:00:03.165953 :BD299DE9: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-08-08 22:00:03.181399 :BD299DF1: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-08-08 22:00:03.197072 :BD299DF9: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-08-08 22:00:03.206844 :BD299E01: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-08-08 22:00:03.222209 :BD299E09: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-08-08 22:00:03.232441 :BD299E11: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-08-08 22:00:03.242219 :BD299E19: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-08-08 22:00:03.254168 :BD299E21: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-08-08 22:00:03.263552 :BD299E29: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-08-08 22:00:03.272926 :BD299E31: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-08-08 22:00:03.290188 :BD299E39: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-08-08 22:00:03.299911 :BD299E41: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-08-08 22:00:03.307474 :BD299E49: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-08-08 22:00:03.315106 :BD299E51: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-08-08 22:00:03.322788 :BD299E59: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-08-08 22:00:03.330430 :BD299E61: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-08-08 22:00:03.338269 :BD299E69: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-08-08 22:00:03.345855 :BD299E71: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-08-08 22:00:03.353344 :BD299E79: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-08-08 22:00:03.360834 :BD299E81: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-08-08 22:00:04.516028 :BD29A22C: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-08-08 22:00:04.516329 :BD29A234: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-08-08 22:00:04.516329*:BD29A23C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028FFC288 curCtx=0x00000000559DEFE0 xsc->flg4=65792 flags=2 2025-08-08 22:00:04.516329*:BD29A23D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028FFC288 2025-08-08 22:00:04.516604 :BD29A23E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-08-08 22:00:04.520764 :BD29A275:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-08 22:00:04.520836 :BD29A280:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-08 22:00:04.521186 :BD29A287: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-08-08 22:00:04.521253 :BD29A28D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 20188, J001)