Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250829220005\orcl_j001_23292_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:35128M/63366M, Ph+PgF:40133M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 23292, image: ORACLE.EXE (J001) *** 2025-08-29 22:00:05.931 *** SESSION ID:(209.16899) 2025-08-29 22:00:05.931 *** 2025-08-29 22:00:05.931 Process diagnostic dump for ORACLE.EXE (J001), OS id=23292, pid: 26, proc_ser: 82, sid: 209, sess_ser: 16899 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23292 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.421371 sec, exc=0.421371 sec, total=0.421371 sec wait times: max=0.500000 sec, heur=2.147580 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 1: 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499260 sec, exc=0.499260 sec, total=0.499260 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.226914 sec, exc=0.226914 sec, total=0.226914 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 16899 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:03 - 22:00:05] 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-08-29 22:00:05.931 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 23292, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-29 21:57:43.399978 :C6605697:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20204 pso_num=26 pso_serial#=66 2025-08-29 21:57:43.400127 :C6605698:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=20204 (legacy spawn) 2025-08-29 21:57:43.406701 :C6605699: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-08-29 21:57:43.406872 :C66056A2: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-08-29 21:57:43.407709 :C66056A3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:57:43.416412 :C66056A6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:57:43.403 2025-08-29 21:57:43.416415 :C66056A7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19952 pso_num=26 pso_serial#=67 2025-08-29 21:57:43.416555 :C66056A8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=19952 (legacy spawn) 2025-08-29 21:57:43.423135 :C66056A9: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-08-29 21:57:43.423303 :C66056B2: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-08-29 21:57:43.424088 :C66056B3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:58:44.743427 :C6606A38:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:58:44.731 2025-08-29 21:58:44.743429 :C6606A39:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22868 pso_num=26 pso_serial#=68 2025-08-29 21:58:44.743568 :C6606A3A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22868 (legacy spawn) 2025-08-29 21:58:44.750064 :C6606A3B: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-08-29 21:58:44.750241 :C6606A42: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-08-29 21:58:44.751049 :C6606A45:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:58:46.505615 :C6606AF0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:58:46.496 2025-08-29 21:58:46.505617 :C6606AF1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22572 pso_num=26 pso_serial#=69 2025-08-29 21:58:46.505758 :C6606AF2:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22572 (legacy spawn) 2025-08-29 21:58:46.512204 :C6606AF3: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-08-29 21:58:46.512371 :C6606AF8: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-08-29 21:58:46.513386 :C6606AFD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:58:52.901736 :C6606D17:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:58:52.887 2025-08-29 21:58:52.901738 :C6606D18:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22496 pso_num=26 pso_serial#=70 2025-08-29 21:58:52.901911 :C6606D19:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22496 (legacy spawn) 2025-08-29 21:58:52.908404 :C6606D1A: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-08-29 21:58:52.908587 :C6606D23: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-08-29 21:58:52.909437 :C6606D24:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:58:52.918130 :C6606D27:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:58:52.903 2025-08-29 21:58:52.918132 :C6606D28:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22312 pso_num=26 pso_serial#=71 2025-08-29 21:58:52.918297 :C6606D29:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22312 (legacy spawn) 2025-08-29 21:58:52.924716 :C6606D2A: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-08-29 21:58:52.924894 :C6606D33: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-08-29 21:58:52.925690 :C6606D34:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:58:56.119263 :C6606E26:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:58:56.106 2025-08-29 21:58:56.119265 :C6606E27:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22660 pso_num=26 pso_serial#=72 2025-08-29 21:58:56.119405 :C6606E28:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22660 (legacy spawn) 2025-08-29 21:58:56.125949 :C6606E29: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-08-29 21:58:56.126131 :C6606E32: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-08-29 21:58:56.126943 :C6606E33:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:58:56.135939 :C6606E36:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:58:56.122 2025-08-29 21:58:56.135941 :C6606E37:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18812 pso_num=26 pso_serial#=73 2025-08-29 21:58:56.136081 :C6606E38:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18812 (legacy spawn) 2025-08-29 21:58:56.143064 :C6606E45: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-08-29 21:58:56.143097 :C6606E48: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-08-29 21:58:56.143247 :C6606E4F: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-08-29 21:58:56.144137 :C6606E5C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:29.180045 :C66078ED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:29.180 2025-08-29 21:59:29.180047 :C66078EE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22388 pso_num=26 pso_serial#=74 2025-08-29 21:59:29.180185 :C66078EF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22388 (legacy spawn) 2025-08-29 21:59:29.186678 :C66078F0: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-08-29 21:59:29.186854 :C66078F5: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-08-29 21:59:29.187641 :C66078FA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:29.196435 :C66078FD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:29.195 2025-08-29 21:59:29.196436 :C66078FE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22064 pso_num=26 pso_serial#=75 2025-08-29 21:59:29.196576 :C66078FF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22064 (legacy spawn) 2025-08-29 21:59:29.202984 :C6607900: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-08-29 21:59:29.203154 :C6607909: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-08-29 21:59:29.203986 :C660790A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:32.240420 :C6607A04:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:32.227 2025-08-29 21:59:32.240422 :C6607A05:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23388 pso_num=26 pso_serial#=76 2025-08-29 21:59:32.240563 :C6607A06:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=23388 (legacy spawn) 2025-08-29 21:59:32.247189 :C6607A13: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-08-29 21:59:32.247361 :C6607A1B: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-08-29 21:59:32.248192 :C6607A1D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:32.256948 :C6607A20:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:32.242 2025-08-29 21:59:32.256950 :C6607A21:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18800 pso_num=26 pso_serial#=77 2025-08-29 21:59:32.257107 :C6607A22:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=18800 (legacy spawn) 2025-08-29 21:59:32.277590 :C6607A23: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-08-29 21:59:32.277761 :C6607A2C: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-08-29 21:59:32.278621 :C6607A2D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:33.491339 :C6607AA5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:33.478 2025-08-29 21:59:33.491341 :C6607AA6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20816 pso_num=26 pso_serial#=78 2025-08-29 21:59:33.491488 :C6607AA7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=20816 (legacy spawn) 2025-08-29 21:59:33.497973 :C6607AA8: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-08-29 21:59:33.498158 :C6607AAD: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-08-29 21:59:33.498944 :C6607AB2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:33.507613 :C6607AB5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:33.494 2025-08-29 21:59:33.507614 :C6607AB6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19788 pso_num=26 pso_serial#=79 2025-08-29 21:59:33.507756 :C6607AB7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=19788 (legacy spawn) 2025-08-29 21:59:33.514251 :C6607AB8: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-08-29 21:59:33.514435 :C6607AC1: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-08-29 21:59:33.515243 :C6607AC2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:46.883364 :C6607EE5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:46.875 2025-08-29 21:59:46.883367 :C6607EE6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22500 pso_num=26 pso_serial#=80 2025-08-29 21:59:46.883509 :C6607EE7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22500 (legacy spawn) 2025-08-29 21:59:46.890005 :C6607EE8: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-08-29 21:59:46.890177 :C6607EF1: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-08-29 21:59:46.890991 :C6607EF2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 21:59:54.535012 :C6608142:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 21:59:54.526 2025-08-29 21:59:54.535015 :C6608143:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22976 pso_num=26 pso_serial#=81 2025-08-29 21:59:54.535153 :C6608144:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=22976 (legacy spawn) 2025-08-29 21:59:54.541675 :C6608145: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-08-29 21:59:54.541851 :C660814A: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-08-29 21:59:54.542670 :C660814F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-29 22:00:01.286160 :C66084E4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-29 22:00:01.275 2025-08-29 22:00:01.286161 :C66084E5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23292 pso_num=26 pso_serial#=82 2025-08-29 22:00:01.286297 :C66084E6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23292 osp_idx=24 osp_ver=760237 osp_pg=0 (spawn #539143) 2025-08-29 22:00:01.286298 :C66084E7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-29 22:00:01.286298 :C66084E8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=248 time=890452580 2025-08-29 22:00:01.286299 :C66084E9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 22:00:01.286299 :C66084EA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 22:00:01.286300 :C66084EB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 22:00:01.286300 :C66084EC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 22:00:01.286300 :C66084ED:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 22:00:01.286785 :C66084F2: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-08-29 22:00:01.291886 :C6608516: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-08-29 22:00:01.291930 :C6608519:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-08-29 22:00:01.295314 :C6608534: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-08-29 22:00:01.683509 :C660887A: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-08-29 22:00:01.727025 :C6608882: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-08-29 22:00:01.742702 :C6608893: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-08-29 22:00:01.799180 :C660889B: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-08-29 22:00:01.932650 :C66088BE: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-08-29 22:00:02.054386 :C66088C6: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-08-29 22:00:02.064252 :C66088CE: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-08-29 22:00:02.080157 :C66088D6: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-08-29 22:00:02.095924 :C66088DE: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-08-29 22:00:02.112225 :C66088E6: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-08-29 22:00:02.122044 :C66088EE: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-08-29 22:00:02.137661 :C66088F6: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-08-29 22:00:02.147368 :C66088FE: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-08-29 22:00:02.163208 :C6608906: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-08-29 22:00:02.173223 :C660890E: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-08-29 22:00:02.183126 :C6608916: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-08-29 22:00:02.199330 :C660891E: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-08-29 22:00:02.209165 :C6608926: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-08-29 22:00:02.225405 :C660892E: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-08-29 22:00:02.241838 :C6608936: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-08-29 22:00:02.257495 :C660893E: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-08-29 22:00:02.273978 :C6608946: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-08-29 22:00:02.284280 :C660894E: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-08-29 22:00:02.300404 :C6608956: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-08-29 22:00:02.310608 :C660895E: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-08-29 22:00:02.320265 :C6608966: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-08-29 22:00:02.330769 :C660896E: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-08-29 22:00:02.347253 :C6608982: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-08-29 22:00:02.357247 :C660898A: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-08-29 22:00:02.373454 :C6608992: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-08-29 22:00:02.380860 :C660899A: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-08-29 22:00:02.391455 :C66089A2: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-08-29 22:00:02.407914 :C66089AA: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-08-29 22:00:02.424232 :C66089B2: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-08-29 22:00:02.433676 :C66089BA: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-08-29 22:00:02.443462 :C66089C2: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-08-29 22:00:02.459436 :C66089CA: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-08-29 22:00:02.475265 :C66089D2: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-08-29 22:00:02.490926 :C66089DA: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-08-29 22:00:02.501212 :C66089E2: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-08-29 22:00:02.510731 :C66089EA: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-08-29 22:00:02.520263 :C66089F2: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-08-29 22:00:02.529893 :C66089FA: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-08-29 22:00:02.541834 :C6608A02: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-08-29 22:00:02.557841 :C6608A0A: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-08-29 22:00:02.567566 :C6608A12: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-08-29 22:00:02.575034 :C6608A1A: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-08-29 22:00:02.582499 :C6608A22: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-08-29 22:00:02.589939 :C6608A2A: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-08-29 22:00:02.597336 :C6608A32: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-08-29 22:00:02.604911 :C6608A3A: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-08-29 22:00:02.612373 :C6608A42: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-08-29 22:00:02.619538 :C6608A4A: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-08-29 22:00:02.626878 :C6608A52: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-08-29 22:00:03.274721*:C6608C1B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000005B5F01B8 estart=1756476002 eid=16810505 ctx=0x0000000783637648 cwh=0x00000000299F0248 cpu=15625 buffg=488 interb=0 r=0 rb=0 w=0 wb=0 2025-08-29 22:00:03.274721*:C6608C1C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000005B5F01B8 curCtx=0x000000005B600EA0 pubTabIdxCnt=0 2025-08-29 22:00:03.274721*:C6608C1D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000005B5F01B8 2025-08-29 22:00:03.274721*:C6608C1E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000005B5F01B8 2025-08-29 22:00:03.274721*:C6608C1F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC288 estart=1756476001 eid=16777374 ctx=0x000000079F654AA8 cwh=0x0000000028BB0158 cpu=1937500 buffg=148407 interb=35667968 r=1623 rb=31080448 w=24 wb=4587520 2025-08-29 22:00:03.274721*:C6608C20:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC288 curCtx=0x000000005B639CB8 pubTabIdxCnt=0 2025-08-29 22:00:03.274721*:C6608C21:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC288 2025-08-29 22:00:03.274721*:C6608C22:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC288 2025-08-29 22:00:03.274721*:C6608C23:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000005B5F01B8 curCtx=0x000000005B600EA0 xsc->flg4=65792 flags=0 2025-08-29 22:00:03.274721*:C6608C24:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000005B5F01B8 2025-08-29 22:00:03.786297 :C6608C6D: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-08-29 22:00:03.786631 :C6608C75: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-08-29 22:00:03.786631*:C6608C7D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC288 curCtx=0x000000005B639CB8 xsc->flg4=65792 flags=2 2025-08-29 22:00:03.786631*:C6608C7E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC288 2025-08-29 22:00:03.786886 :C6608C7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-08-29 22:00:03.788552 :C6608C80: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-08-29 22:00:03.791590 :C6608C88: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-08-29 22:00:03.791691 :C6608C8B: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-08-29 22:00:03.791763 :C6608C98: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-08-29 22:00:04.025684 :C6608CA9: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: 23292, J001)