Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916220008\orcl_j001_24112_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:35952M/63366M, Ph+PgF:40243M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 24112, image: ORACLE.EXE (J001) *** 2025-09-16 22:00:08.957 *** SESSION ID:(209.19677) 2025-09-16 22:00:08.957 *** 2025-09-16 22:00:08.957 Process diagnostic dump for ORACLE.EXE (J001), OS id=24112, pid: 26, proc_ser: 84, sid: 209, sess_ser: 19677 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24112 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.354880 sec, exc=0.354880 sec, total=0.354880 sec wait times: max=0.500000 sec, heur=4.347668 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500181 sec, exc=0.500181 sec, total=0.500181 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 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=2 seq_num=3 snap_id=1 wait times: snap=0.500109 sec, exc=0.500109 sec, total=0.500109 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.492398 sec, exc=0.492398 sec, total=0.492398 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 19677 --------------------------------------------------- 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:04 - 22:00:08] 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-09-16 22:00:08.957 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 24112, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-16 21:57:40.162393 :CE5D4D3C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16524 pso_num=26 pso_serial#=68 2025-09-16 21:57:40.162538 :CE5D4D3D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=16524 (legacy spawn) 2025-09-16 21:57:40.180119 :CE5D4D3E: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-09-16 21:57:40.180307 :CE5D4D43: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-09-16 21:57:40.181307 :CE5D4D48:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:57:46.489255 :CE5D4F51:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:57:46.489 2025-09-16 21:57:46.489255 :CE5D4F52:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21816 pso_num=26 pso_serial#=69 2025-09-16 21:57:46.489393 :CE5D4F55:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=21816 (legacy spawn) 2025-09-16 21:57:46.496279 :CE5D4F56: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-09-16 21:57:46.496464 :CE5D4F5F: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-09-16 21:57:46.497340 :CE5D4F60:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:57:46.507121 :CE5D4F85:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:57:46.505 2025-09-16 21:57:46.507123 :CE5D4F86:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23100 pso_num=26 pso_serial#=70 2025-09-16 21:57:46.507268 :CE5D4F87:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23100 (legacy spawn) 2025-09-16 21:57:46.514195 :CE5D4F88: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-09-16 21:57:46.514371 :CE5D4F90: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-09-16 21:57:46.515223 :CE5D4F92:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:58:23.197711 :CE5D5BD0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:58:23.183 2025-09-16 21:58:23.197713 :CE5D5BD1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13480 pso_num=26 pso_serial#=71 2025-09-16 21:58:23.197850 :CE5D5BD2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13480 (legacy spawn) 2025-09-16 21:58:23.204612 :CE5D5BD3: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-09-16 21:58:23.204793 :CE5D5BDC: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-09-16 21:58:23.205593 :CE5D5BDD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:58:23.214463 :CE5D5BE0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:58:23.214 2025-09-16 21:58:23.214465 :CE5D5BE1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23732 pso_num=26 pso_serial#=72 2025-09-16 21:58:23.214607 :CE5D5BE2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23732 (legacy spawn) 2025-09-16 21:58:23.221305 :CE5D5BE3: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-09-16 21:58:23.221470 :CE5D5BEC: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-09-16 21:58:23.222279 :CE5D5BED:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:58:26.507063 :CE5D5CD5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:58:26.496 2025-09-16 21:58:26.507065 :CE5D5CD6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22500 pso_num=26 pso_serial#=73 2025-09-16 21:58:26.507202 :CE5D5CD7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22500 (legacy spawn) 2025-09-16 21:58:26.513976 :CE5D5CD8: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-09-16 21:58:26.514150 :CE5D5CDE: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-09-16 21:58:26.514960 :CE5D5CE2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:58:26.523846 :CE5D5CE5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:58:26.511 2025-09-16 21:58:26.523848 :CE5D5CE6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24532 pso_num=26 pso_serial#=74 2025-09-16 21:58:26.523991 :CE5D5CE7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=24532 (legacy spawn) 2025-09-16 21:58:26.530738 :CE5D5CE8: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-09-16 21:58:26.530910 :CE5D5CF0: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-09-16 21:58:26.531717 :CE5D5CF2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:58:34.185297 :CE5D5F41:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:58:34.176 2025-09-16 21:58:34.185300 :CE5D5F42:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24184 pso_num=26 pso_serial#=75 2025-09-16 21:58:34.185453 :CE5D5F43:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=24184 (legacy spawn) 2025-09-16 21:58:34.192429 :CE5D5F44: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-09-16 21:58:34.192603 :CE5D5F49: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-09-16 21:58:34.193487 :CE5D5F4E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:02.696260 :CE5D68F0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:02.688 2025-09-16 21:59:02.696262 :CE5D68F1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23064 pso_num=26 pso_serial#=76 2025-09-16 21:59:02.696410 :CE5D68F2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23064 (legacy spawn) 2025-09-16 21:59:02.703155 :CE5D68F3: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-09-16 21:59:02.703321 :CE5D68F8: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-09-16 21:59:02.704229 :CE5D68FD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:02.713630 :CE5D6900:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:02.704 2025-09-16 21:59:02.713632 :CE5D6901:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24964 pso_num=26 pso_serial#=77 2025-09-16 21:59:02.713780 :CE5D6902:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=24964 (legacy spawn) 2025-09-16 21:59:02.720773 :CE5D6903: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-09-16 21:59:02.720938 :CE5D690C: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-09-16 21:59:02.721831 :CE5D690D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:22.526963 :CE5D700D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:22.517 2025-09-16 21:59:22.526965 :CE5D700E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22520 pso_num=26 pso_serial#=78 2025-09-16 21:59:22.527106 :CE5D700F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22520 (legacy spawn) 2025-09-16 21:59:22.533839 :CE5D7010: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-09-16 21:59:22.534017 :CE5D7019: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-09-16 21:59:22.534850 :CE5D701A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:35.828566 :CE5D7429:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:35.813 2025-09-16 21:59:35.828568 :CE5D742A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10356 pso_num=26 pso_serial#=79 2025-09-16 21:59:35.828729 :CE5D742B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10356 (legacy spawn) 2025-09-16 21:59:35.835452 :CE5D742C: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-09-16 21:59:35.835626 :CE5D7431: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-09-16 21:59:35.836438 :CE5D7436:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:35.845543 :CE5D7439:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:35.844 2025-09-16 21:59:35.845545 :CE5D743A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23480 pso_num=26 pso_serial#=80 2025-09-16 21:59:35.845688 :CE5D743B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23480 (legacy spawn) 2025-09-16 21:59:35.852388 :CE5D743C: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-09-16 21:59:35.852555 :CE5D7444: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-09-16 21:59:35.853353 :CE5D7446:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:36.353280 :CE5D7478:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:36.344 2025-09-16 21:59:36.353282 :CE5D7479:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24144 pso_num=26 pso_serial#=81 2025-09-16 21:59:36.353440 :CE5D747A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=24144 (legacy spawn) 2025-09-16 21:59:36.360356 :CE5D747B: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-09-16 21:59:36.360527 :CE5D7480: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-09-16 21:59:36.361343 :CE5D7485:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:38.992442 :CE5D7573:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:38.990 2025-09-16 21:59:38.992445 :CE5D7574:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23144 pso_num=26 pso_serial#=82 2025-09-16 21:59:38.992585 :CE5D7575:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23144 (legacy spawn) 2025-09-16 21:59:39.007436 :CE5D7576: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-09-16 21:59:39.007621 :CE5D757B: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-09-16 21:59:39.008532 :CE5D7580:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 21:59:39.017610 :CE5D7583:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 21:59:39.005 2025-09-16 21:59:39.017613 :CE5D7584:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23448 pso_num=26 pso_serial#=83 2025-09-16 21:59:39.017747 :CE5D7585:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23448 (legacy spawn) 2025-09-16 21:59:39.024524 :CE5D7586: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-09-16 21:59:39.024695 :CE5D758E: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-09-16 21:59:39.025492 :CE5D758F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-09-16 22:00:02.077367 :CE5D7E8C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-16 22:00:02.065 2025-09-16 22:00:02.077369 :CE5D7E8D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24112 pso_num=26 pso_serial#=84 2025-09-16 22:00:02.077505 :CE5D7E8E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24112 osp_idx=24 osp_ver=859699 osp_pg=0 (spawn #599184) 2025-09-16 22:00:02.077506 :CE5D7E8F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 22:00:02.077506 :CE5D7E90:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=101 time=2445653377 2025-09-16 22:00:02.077507 :CE5D7E91:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 22:00:02.077507 :CE5D7E92:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 22:00:02.077508 :CE5D7E93:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 22:00:02.077508 :CE5D7E94:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 22:00:02.077509 :CE5D7E95:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 22:00:02.078107 :CE5D7E9F: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-09-16 22:00:02.083522 :CE5D7EB6: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-09-16 22:00:02.083566 :CE5D7EC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-09-16 22:00:02.087588 :CE5D7EDC: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-09-16 22:00:02.473738 :CE5D827C: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-09-16 22:00:02.525598 :CE5D8284: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-09-16 22:00:02.541374 :CE5D828F: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-09-16 22:00:02.597791 :CE5D82A1: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-09-16 22:00:02.729200 :CE5D82B1: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-09-16 22:00:02.844441 :CE5D82BA: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-09-16 22:00:02.854309 :CE5D82C2: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-09-16 22:00:02.870417 :CE5D82CA: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-09-16 22:00:02.886065 :CE5D82D2: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-09-16 22:00:02.902362 :CE5D82DA: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-09-16 22:00:02.912092 :CE5D82E2: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-09-16 22:00:02.922260 :CE5D82EA: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-09-16 22:00:02.938095 :CE5D82F2: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-09-16 22:00:02.954169 :CE5D82FA: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-09-16 22:00:02.964111 :CE5D8302: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-09-16 22:00:02.974046 :CE5D830A: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-09-16 22:00:02.989724 :CE5D8312: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-09-16 22:00:02.999450 :CE5D831A: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-09-16 22:00:03.015674 :CE5D8322: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-09-16 22:00:03.031971 :CE5D834D: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-09-16 22:00:03.047994 :CE5D8355: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-09-16 22:00:03.055564 :CE5D835D: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-09-16 22:00:03.071279 :CE5D8365: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-09-16 22:00:03.081280 :CE5D836D: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-09-16 22:00:03.091470 :CE5D8375: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-09-16 22:00:03.107631 :CE5D837D: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-09-16 22:00:03.117373 :CE5D8385: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-09-16 22:00:03.127296 :CE5D838D: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-09-16 22:00:03.143222 :CE5D8395: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-09-16 22:00:03.152929 :CE5D839D: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-09-16 22:00:03.168736 :CE5D83A5: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-09-16 22:00:03.179073 :CE5D83AD: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-09-16 22:00:03.194888 :CE5D83B5: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-09-16 22:00:03.210437 :CE5D83BD: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-09-16 22:00:03.220295 :CE5D83C5: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-09-16 22:00:03.229833 :CE5D83CD: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-09-16 22:00:03.245436 :CE5D83D5: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-09-16 22:00:03.260980 :CE5D83DD: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-09-16 22:00:03.270381 :CE5D83E5: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-09-16 22:00:03.286232 :CE5D83F9: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-09-16 22:00:03.295742 :CE5D8401: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-09-16 22:00:03.305960 :CE5D8409: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-09-16 22:00:03.315545 :CE5D8411: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-09-16 22:00:03.327758 :CE5D8419: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-09-16 22:00:03.343740 :CE5D8421: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-09-16 22:00:03.353534 :CE5D8429: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-09-16 22:00:03.360871 :CE5D8431: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-09-16 22:00:03.368294 :CE5D8439: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-09-16 22:00:03.375872 :CE5D8441: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-09-16 22:00:03.383275 :CE5D8449: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-09-16 22:00:03.390754 :CE5D8451: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-09-16 22:00:03.398390 :CE5D8459: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-09-16 22:00:03.405968 :CE5D8461: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-09-16 22:00:03.413288 :CE5D8469: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-09-16 22:00:03.420780 :CE5D8471: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-09-16 22:00:03.439795*:CE5D8481:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299C6918 estart=1758031202 eid=16777569 ctx=0x00000007831EA738 cwh=0x00000000299F0248 cpu=15625 buffg=2496 interb=114688 r=7 rb=114688 w=0 wb=0 2025-09-16 22:00:03.439795*:CE5D8482:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299C6918 curCtx=0x0000000060AB1CD0 pubTabIdxCnt=0 2025-09-16 22:00:03.439795*:CE5D8483:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299C6918 2025-09-16 22:00:03.439795*:CE5D8484:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299C6918 2025-09-16 22:00:03.439795*:CE5D8487:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC288 estart=1758031201 eid=16777392 ctx=0x000000079F654AA8 cwh=0x0000000028BB0158 cpu=1250000 buffg=89655 interb=28409856 r=1459 rb=26132480 w=12 wb=2277376 2025-09-16 22:00:03.439795*:CE5D8488:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC288 curCtx=0x0000000060AD4F98 pubTabIdxCnt=0 2025-09-16 22:00:03.439795*:CE5D8489:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC288 2025-09-16 22:00:03.439795*:CE5D848A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC288 2025-09-16 22:00:03.971066*:CE5D8583:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299C6918 curCtx=0x0000000060AB1CD0 xsc->flg4=65792 flags=1 2025-09-16 22:00:03.971066*:CE5D8584:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299C6918 2025-09-16 22:00:03.976940 :CE5D8585:db_trace:ktu.c@4871:ktuaex1r(): [10442:26:209] Extension usn 8, ext 1, nex 3 2025-09-16 22:00:03.976947 :CE5D8586:db_trace:ktusm.c@2117:ktusmasp(): [10445:26:209] des1:tsn:1 rdba:0x0081a1e1 where: kduwh01: kdusru 2025-09-16 22:00:04.607124 :CE5D86BA: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-09-16 22:00:04.607452 :CE5D86C2: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-09-16 22:00:04.607452*:CE5D86C5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC288 curCtx=0x0000000060AD4F98 xsc->flg4=65792 flags=2 2025-09-16 22:00:04.607452*:CE5D86C6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC288 2025-09-16 22:00:04.607778 :CE5D86CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-09-16 22:00:04.612193 :CE5D86CD: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-09-16 22:00:04.612280 :CE5D86D0: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 24112, J001)