Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250701220006\orcl_j001_15736_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:36765M/63366M, Ph+PgF:42770M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15736, image: ORACLE.EXE (J001) *** 2025-07-01 22:00:07.047 *** SESSION ID:(209.40449) 2025-07-01 22:00:07.047 *** 2025-07-01 22:00:07.047 Process diagnostic dump for ORACLE.EXE (J001), OS id=15736, pid: 26, proc_ser: 27, sid: 209, sess_ser: 40449 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15736 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.040554 sec, exc=0.040554 sec, total=0.040554 sec wait times: max=0.500000 sec, heur=4.053211 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500108 sec, exc=0.500108 sec, total=0.500108 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499187 sec, exc=0.499187 sec, total=0.499187 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.016433 sec, exc=0.016433 sec, total=0.016433 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.496862 sec, exc=0.496862 sec, total=0.496862 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 209 serial 40449 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:03 - 22:00:07] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-01 22:00:07.047 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15736, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-01 21:57:31.851777 :AC421131:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:57:31.852584 :AC421132:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:57:43.548194 :AC42144D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:57:43.539 2025-07-01 21:57:43.548196 :AC42144E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14556 pso_num=26 pso_serial#=12 2025-07-01 21:57:43.548334 :AC42144F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14556 (legacy spawn) 2025-07-01 21:57:43.554777 :AC421450:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:57:43.554954 :AC421455:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:57:43.555778 :AC42145A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:57:44.113421 :AC4214CF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:57:44.101 2025-07-01 21:57:44.113423 :AC4214D0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14984 pso_num=26 pso_serial#=13 2025-07-01 21:57:44.113563 :AC4214D1:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14984 (legacy spawn) 2025-07-01 21:57:44.120070 :AC4214D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:57:44.120234 :AC4214DB:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:57:44.121035 :AC4214DC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:57:44.129728 :AC4214DF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:57:44.117 2025-07-01 21:57:44.129730 :AC4214E0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17008 pso_num=26 pso_serial#=14 2025-07-01 21:57:44.129866 :AC4214E1:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17008 (legacy spawn) 2025-07-01 21:57:44.136451 :AC4214E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:57:44.136612 :AC4214EA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:57:44.137406 :AC4214EC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:58:17.561496 :AC421F67:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:58:17.553 2025-07-01 21:58:17.561498 :AC421F68:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11628 pso_num=26 pso_serial#=15 2025-07-01 21:58:17.561635 :AC421F69:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11628 (legacy spawn) 2025-07-01 21:58:17.568098 :AC421F6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:58:17.568284 :AC421F73:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:58:17.569063 :AC421F74:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:58:20.404496 :AC42205D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:58:20.401 2025-07-01 21:58:20.404499 :AC42205E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11212 pso_num=26 pso_serial#=16 2025-07-01 21:58:20.404692 :AC42205F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11212 (legacy spawn) 2025-07-01 21:58:20.411100 :AC422060:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:58:20.411280 :AC422069:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:58:20.412084 :AC42206A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:58:20.420813 :AC42206D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:58:20.417 2025-07-01 21:58:20.420815 :AC42206E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10480 pso_num=26 pso_serial#=17 2025-07-01 21:58:20.420950 :AC42206F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10480 (legacy spawn) 2025-07-01 21:58:20.427403 :AC422070:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:58:20.427573 :AC422078:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:58:20.428365 :AC42207A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:58:23.532070 :AC422167:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:58:23.519 2025-07-01 21:58:23.532072 :AC422168:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9028 pso_num=26 pso_serial#=18 2025-07-01 21:58:23.532204 :AC422169:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9028 (legacy spawn) 2025-07-01 21:58:23.538662 :AC42216A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:58:23.538850 :AC422173:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:58:23.539610 :AC422174:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:58:23.548393 :AC422177:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:58:23.534 2025-07-01 21:58:23.548396 :AC422178:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11764 pso_num=26 pso_serial#=19 2025-07-01 21:58:23.548537 :AC422179:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11764 (legacy spawn) 2025-07-01 21:58:23.555034 :AC42217A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:58:23.555192 :AC422183:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:58:23.555992 :AC422184:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:25.745250 :AC423527:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:25.732 2025-07-01 21:59:25.745252 :AC423528:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6552 pso_num=26 pso_serial#=20 2025-07-01 21:59:25.745392 :AC423529:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=6552 (legacy spawn) 2025-07-01 21:59:25.751813 :AC42352A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:25.751992 :AC42352F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:25.752779 :AC423534:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:32.818172 :AC42376D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:32.803 2025-07-01 21:59:32.818174 :AC42376E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8240 pso_num=26 pso_serial#=21 2025-07-01 21:59:32.818356 :AC42376F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8240 (legacy spawn) 2025-07-01 21:59:32.824741 :AC423770:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:32.824913 :AC423778:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:32.825748 :AC42377A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:32.834414 :AC42377D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:32.819 2025-07-01 21:59:32.834416 :AC42377E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15780 pso_num=26 pso_serial#=22 2025-07-01 21:59:32.834553 :AC42377F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15780 (legacy spawn) 2025-07-01 21:59:32.841013 :AC423780:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:32.841184 :AC423788:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:32.841966 :AC42378A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:36.045591 :AC423882:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:36.038 2025-07-01 21:59:36.045593 :AC423883:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17060 pso_num=26 pso_serial#=23 2025-07-01 21:59:36.045733 :AC423884:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=17060 (legacy spawn) 2025-07-01 21:59:36.052141 :AC423885:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:36.052309 :AC42388E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:36.053102 :AC42388F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:36.061755 :AC423892:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:36.053 2025-07-01 21:59:36.061757 :AC423893:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1812 pso_num=26 pso_serial#=24 2025-07-01 21:59:36.061899 :AC423894:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=1812 (legacy spawn) 2025-07-01 21:59:36.068389 :AC423895:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:36.068555 :AC42389E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:36.069389 :AC42389F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:36.202228 :AC4238C5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:36.194 2025-07-01 21:59:36.202230 :AC4238C6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1216 pso_num=26 pso_serial#=25 2025-07-01 21:59:36.202364 :AC4238C7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=1216 (legacy spawn) 2025-07-01 21:59:36.208730 :AC4238C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:36.208897 :AC4238CD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:36.209720 :AC4238D2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 21:59:38.639882 :AC423983:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 21:59:38.632 2025-07-01 21:59:38.639885 :AC423984:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16044 pso_num=26 pso_serial#=26 2025-07-01 21:59:38.640026 :AC423985:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16044 (legacy spawn) 2025-07-01 21:59:38.646486 :AC423986:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 21:59:38.646668 :AC42398F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 21:59:38.647549 :AC423990:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-01 22:00:00.487096 :AC4241F4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-01 22:00:00.476 2025-07-01 22:00:00.487098 :AC4241F5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15736 pso_num=26 pso_serial#=27 2025-07-01 22:00:00.487228 :AC4241F6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15736 osp_idx=24 osp_ver=542922 osp_pg=0 (spawn #342500) 2025-07-01 22:00:00.487229 :AC4241F7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-01 22:00:00.487229 :AC4241F8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=4 time=87976376 2025-07-01 22:00:00.487229 :AC4241F9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-01 22:00:00.487230 :AC4241FA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-01 22:00:00.487230 :AC4241FB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-01 22:00:00.487230 :AC4241FC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-01 22:00:00.487231 :AC4241FD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-01 22:00:00.487701 :AC424207:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-01 22:00:00.492834 :AC424226:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:00.492874 :AC424229:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-01 22:00:00.496328 :AC424244:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:00.875706 :AC424578:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:00.919571 :AC424580:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:00.935379 :AC424590:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:00.992807 :AC424598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.125239 :AC4245B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.239496 :AC4245BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.249120 :AC4245C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.264324 :AC4245CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.279457 :AC4245D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.295127 :AC4245E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.304491 :AC4245F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.319715 :AC4245F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.329053 :AC424601:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.344273 :AC424609:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.353831 :AC424611:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.363006 :AC424619:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.378124 :AC424621:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.387593 :AC424629:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.403311 :AC424631:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.418945 :AC424639:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.434047 :AC424641:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.441532 :AC424649:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.457699 :AC424651:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.467055 :AC424659:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.482169 :AC424661:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.491246 :AC424669:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.500981 :AC424671:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.510343 :AC424679:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.525669 :AC424681:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.540767 :AC424689:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.549765 :AC424691:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.559745 :AC424699:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.574833 :AC4246A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.589911 :AC4246A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.597303 :AC4246B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.606545 :AC4246B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.616077 :AC4246C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.631037 :AC4246C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.646292 :AC4246D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.661592 :AC4246D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.670820 :AC4246E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.680414 :AC4246E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.692333 :AC4246F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.702282 :AC4246F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.711931 :AC424701:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.727126 :AC424709:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.734630 :AC424711:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.742009 :AC424719:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.749246 :AC424721:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.756707 :AC424729:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.763985 :AC424731:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.771317 :AC424739:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.778634 :AC424741:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.785888 :AC424749:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.793392 :AC424751:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.800715 :AC424759:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.808126 :AC424761:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.815518 :AC424769:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:01.823062 :AC424771:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:02.147586*:AC4247F1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299C6918 estart=1751378401 eid=16777415 ctx=0x00000007831EA738 cwh=0x00000000299F0248 cpu=328125 buffg=55437 interb=1572864 r=15 rb=901120 w=3 wb=671744 2025-07-01 22:00:02.147586*:AC4247F2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299C6918 curCtx=0x000000004A82CC88 pubTabIdxCnt=0 2025-07-01 22:00:02.147586*:AC4247F3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299C6918 2025-07-01 22:00:02.147586*:AC4247F4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299C6918 2025-07-01 22:00:02.147586*:AC4247F5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000027ADC288 estart=1751378400 eid=16777315 ctx=0x000000079F654AA8 cwh=0x0000000027AD0158 cpu=1593750 buffg=144046 interb=31735808 r=1579 rb=28770304 w=15 wb=2965504 2025-07-01 22:00:02.147586*:AC4247F6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000027ADC288 curCtx=0x00000000299C8510 pubTabIdxCnt=0 2025-07-01 22:00:02.147586*:AC4247F7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000027ADC288 2025-07-01 22:00:02.147586*:AC4247F8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000027ADC288 2025-07-01 22:00:02.366334*:AC42493F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299C6918 curCtx=0x000000004A82CC88 xsc->flg4=65792 flags=1 2025-07-01 22:00:02.366334*:AC424940:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299C6918 2025-07-01 22:00:02.990118 :AC424982:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:02.990459 :AC42498A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:02.990459*:AC424992:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000027ADC288 curCtx=0x00000000299C8510 xsc->flg4=65792 flags=2 2025-07-01 22:00:02.990459*:AC424993:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000027ADC288 2025-07-01 22:00:02.990718 :AC424994:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-01 22:00:02.995314 :AC424995:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-01 22:00:02.995402 :AC424998:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-01 22:00:02.995477 :AC4249A5:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-01 22:00:04.015629 :AC424A2D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 15736, J001)