Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614140533\orcl_j002_14632_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:36684M/63366M, Ph+PgF:43185M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 14632, image: ORACLE.EXE (J002) *** 2025-06-14 14:05:33.224 *** SESSION ID:(219.383) 2025-06-14 14:05:33.224 *** 2025-06-14 14:05:33.224 Process diagnostic dump for ORACLE.EXE (J002), OS id=14632, pid: 27, proc_ser: 144, sid: 219, sess_ser: 383 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14632 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) 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.017639 sec, exc=0.017639 sec, total=0.017639 sec wait times: max=0.500000 sec, heur=4.507870 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.000012 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.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.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.000008 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.500128 sec, exc=0.500128 sec, total=0.500128 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000009 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.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.500152 sec, exc=0.500152 sec, total=0.500152 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.501149 sec, exc=0.501149 sec, total=0.501149 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 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.499997 sec, exc=0.499997 sec, total=0.499997 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.488734 sec, exc=0.488734 sec, total=0.488734 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 219 serial 383 --------------------------------------------------- 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, 14:05:28 - 14:05:33] idle wait at each sample [session created at: 14:05:28] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 14:05:33.224 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 14632, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 14:00:55.231288 :A497EC34:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:00:55.232085 :A497EC39:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:00:57.474867 :A497ECF3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:00:57.467 2025-06-14 14:00:57.474870 :A497ECF4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11816 pso_num=27 pso_serial#=119 2025-06-14 14:00:57.475005 :A497ECF5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11816 (legacy spawn) 2025-06-14 14:00:57.481487 :A497ECF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:00:57.481656 :A497ECFB:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:00:57.482526 :A497ED00:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:01:28.575652 :A497F741:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:01:28.563 2025-06-14 14:01:28.575654 :A497F742:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14664 pso_num=27 pso_serial#=120 2025-06-14 14:01:28.575798 :A497F743:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14664 (legacy spawn) 2025-06-14 14:01:28.582212 :A497F744:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:01:28.582388 :A497F74D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:01:28.583203 :A497F74E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:01:29.450539 :A497F7AA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:01:29.438 2025-06-14 14:01:29.450541 :A497F7AB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14752 pso_num=27 pso_serial#=121 2025-06-14 14:01:29.450676 :A497F7AC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14752 (legacy spawn) 2025-06-14 14:01:29.457150 :A497F7AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:01:29.457314 :A497F7B6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:01:29.458150 :A497F7B7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:01:44.691593 :A497FC6A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:01:44.677 2025-06-14 14:01:44.691595 :A497FC6B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14768 pso_num=27 pso_serial#=122 2025-06-14 14:01:44.691736 :A497FC6C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14768 (legacy spawn) 2025-06-14 14:01:44.698194 :A497FC6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:01:44.698374 :A497FC75:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:01:44.699167 :A497FC77:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:01:44.707740 :A497FC7A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:01:44.693 2025-06-14 14:01:44.707742 :A497FC7B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14328 pso_num=27 pso_serial#=123 2025-06-14 14:01:44.707877 :A497FC7C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14328 (legacy spawn) 2025-06-14 14:01:44.725717 :A497FC7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:01:44.725889 :A497FC86:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:01:44.726768 :A497FC87:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:01:47.789082 :A497FD85:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:01:47.776 2025-06-14 14:01:47.789084 :A497FD86:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16084 pso_num=27 pso_serial#=124 2025-06-14 14:01:47.789222 :A497FD87:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16084 (legacy spawn) 2025-06-14 14:01:47.795831 :A497FD88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:01:47.796016 :A497FD8D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:01:47.796832 :A497FD92:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:01:47.805842 :A497FD95:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:01:47.791 2025-06-14 14:01:47.805843 :A497FD96:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15292 pso_num=27 pso_serial#=125 2025-06-14 14:01:47.805986 :A497FD97:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15292 (legacy spawn) 2025-06-14 14:01:47.812667 :A497FD98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:01:47.812841 :A497FDA1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:01:47.813678 :A497FDA2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:02:30.726824 :A4980CA0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:02:30.718 2025-06-14 14:02:30.726826 :A4980CA1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16460 pso_num=27 pso_serial#=126 2025-06-14 14:02:30.726958 :A4980CA2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16460 (legacy spawn) 2025-06-14 14:02:30.733430 :A4980CA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:02:30.733613 :A4980CA8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:02:30.734433 :A4980CAD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:02:37.559418 :A4980EB0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:02:37.551 2025-06-14 14:02:37.559419 :A4980EB1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16252 pso_num=27 pso_serial#=127 2025-06-14 14:02:37.559567 :A4980EB2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16252 (legacy spawn) 2025-06-14 14:02:37.566124 :A4980EB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:02:37.566316 :A4980EB8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:02:37.567110 :A4980EBD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:02:57.090288 :A4981516:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:02:57.076 2025-06-14 14:02:57.090290 :A4981517:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14700 pso_num=27 pso_serial#=128 2025-06-14 14:02:57.090423 :A4981518:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14700 (legacy spawn) 2025-06-14 14:02:57.096894 :A4981519:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:02:57.097065 :A498151E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:02:57.097849 :A4981523:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:02:57.106424 :A4981526:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:02:57.092 2025-06-14 14:02:57.106425 :A4981527:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15480 pso_num=27 pso_serial#=129 2025-06-14 14:02:57.106560 :A4981528:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15480 (legacy spawn) 2025-06-14 14:02:57.113013 :A4981529:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:02:57.113182 :A4981532:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:02:57.113968 :A4981533:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:30.987915 :A498208F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:30.985 2025-06-14 14:03:30.987917 :A4982090:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13636 pso_num=27 pso_serial#=130 2025-06-14 14:03:30.988055 :A4982091:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13636 (legacy spawn) 2025-06-14 14:03:30.994709 :A4982092:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:30.994904 :A4982097:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:30.995784 :A498209C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:31.004774 :A498209F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:31.001 2025-06-14 14:03:31.004776 :A49820A0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16764 pso_num=27 pso_serial#=131 2025-06-14 14:03:31.004927 :A49820A1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16764 (legacy spawn) 2025-06-14 14:03:31.011913 :A49820A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:31.012085 :A49820AB:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:31.013126 :A49820AC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:32.894105 :A498212C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:32.886 2025-06-14 14:03:32.894107 :A498212D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13904 pso_num=27 pso_serial#=132 2025-06-14 14:03:32.894241 :A498212E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13904 (legacy spawn) 2025-06-14 14:03:32.900707 :A498212F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:32.900873 :A4982138:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:32.901704 :A4982139:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:33.462901 :A4982182:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:33.449 2025-06-14 14:03:33.462903 :A4982183:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16500 pso_num=27 pso_serial#=133 2025-06-14 14:03:33.463042 :A4982184:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16500 (legacy spawn) 2025-06-14 14:03:33.469509 :A4982185:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:33.469675 :A498218A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:33.470466 :A498218F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:33.479097 :A4982192:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:33.464 2025-06-14 14:03:33.479099 :A4982193:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16988 pso_num=27 pso_serial#=134 2025-06-14 14:03:33.479236 :A4982194:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16988 (legacy spawn) 2025-06-14 14:03:33.485785 :A4982195:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:33.485941 :A498219E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:33.486730 :A498219F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:36.606647 :A4982291:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:36.594 2025-06-14 14:03:36.606649 :A4982292:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15104 pso_num=27 pso_serial#=135 2025-06-14 14:03:36.606795 :A4982293:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15104 (legacy spawn) 2025-06-14 14:03:36.613354 :A4982294:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:36.613532 :A4982299:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:36.614324 :A498229E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:36.622974 :A49822A1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:36.610 2025-06-14 14:03:36.622976 :A49822A2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14672 pso_num=27 pso_serial#=136 2025-06-14 14:03:36.623103 :A49822A3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14672 (legacy spawn) 2025-06-14 14:03:36.629630 :A49822A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:36.629800 :A49822AD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:36.630634 :A49822AE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:03:45.722241 :A4982578:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:03:45.713 2025-06-14 14:03:45.722244 :A4982579:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15416 pso_num=27 pso_serial#=137 2025-06-14 14:03:45.722392 :A498257A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15416 (legacy spawn) 2025-06-14 14:03:45.729294 :A498257B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:03:45.729467 :A4982580:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:03:45.730320 :A4982585:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:04:35.062627 :A4983551:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:04:35.049 2025-06-14 14:04:35.062629 :A4983552:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12508 pso_num=27 pso_serial#=138 2025-06-14 14:04:35.062772 :A4983553:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12508 (legacy spawn) 2025-06-14 14:04:35.069438 :A4983554:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:04:35.069609 :A4983559:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:04:35.070465 :A498355E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:04:45.988861 :A49838EA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:04:45.975 2025-06-14 14:04:45.988863 :A49838EB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8784 pso_num=27 pso_serial#=139 2025-06-14 14:04:45.988993 :A49838EC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8784 (legacy spawn) 2025-06-14 14:04:45.995427 :A49838ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:04:45.995607 :A49838F5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:04:45.996395 :A49838F7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:04:46.004924 :A49838FA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:04:45.990 2025-06-14 14:04:46.004926 :A49838FB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14460 pso_num=27 pso_serial#=140 2025-06-14 14:04:46.005056 :A49838FC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14460 (legacy spawn) 2025-06-14 14:04:46.011858 :A4983920:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:04:46.012026 :A4983929:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:04:46.012840 :A498392A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:04:49.092498 :A4983A26:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:04:49.079 2025-06-14 14:04:49.092501 :A4983A27:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15696 pso_num=27 pso_serial#=141 2025-06-14 14:04:49.092650 :A4983A28:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15696 (legacy spawn) 2025-06-14 14:04:49.099206 :A4983A29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:04:49.099374 :A4983A2E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:04:49.100181 :A4983A33:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:04:49.118785 :A4983A36:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:04:49.110 2025-06-14 14:04:49.118787 :A4983A37:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16392 pso_num=27 pso_serial#=142 2025-06-14 14:04:49.118926 :A4983A38:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16392 (legacy spawn) 2025-06-14 14:04:49.125569 :A4983A39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:04:49.125793 :A4983A42:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:04:49.126675 :A4983A43:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:04:53.790010 :A4983BA8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:04:53.785 2025-06-14 14:04:53.790012 :A4983BA9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16304 pso_num=27 pso_serial#=143 2025-06-14 14:04:53.790153 :A4983BAA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16304 (legacy spawn) 2025-06-14 14:04:53.796615 :A4983BAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:04:53.796796 :A4983BB0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 14:04:53.797586 :A4983BB5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 14:05:25.291183 :A4984A46:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 14:05:25.286 2025-06-14 14:05:25.291186 :A4984A47:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14632 pso_num=27 pso_serial#=144 2025-06-14 14:05:25.291328 :A4984A48:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=14632 osp_idx=25 osp_ver=65678 osp_pg=0 (spawn #284725) 2025-06-14 14:05:25.291329 :A4984A49:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 14:05:25.291330 :A4984A4A:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=64 time=2885668485 2025-06-14 14:05:25.291330 :A4984A4B:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 14:05:25.291331 :A4984A4C:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 14:05:25.291331 :A4984A4D:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 14:05:25.291331 :A4984A4E:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 14:05:25.291332 :A4984A4F:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 14:05:25.291833 :A4984A59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 14:05:28.707330 :A4985279:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 14:05:28.707359*:A498527B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028CABB58 estart=1749881128 eid=16909079 ctx=0x000000077BD98A08 cwh=0x0000000028C601B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-14 14:05:28.707359*:A498527C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028CABB58 curCtx=0x0000000028C6F708 pubTabIdxCnt=0 2025-06-14 14:05:28.707359*:A498527D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028CABB58 2025-06-14 14:05:28.707359*:A498527E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028CABB58 2025-06-14 14:05:28.707359*:A498527F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028CABB58 curCtx=0x0000000028C6F708 xsc->flg4=65792 flags=2 2025-06-14 14:05:28.707359*:A4985280:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028CABB58 2025-06-14 14:05:28.707951 :A4985281:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:05:28.715503 :A4985289:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:psdpgi.c:2235 2025-06-14 14:05:28.715922 :A498528A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:05:28.715959 :A498528D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:psdpgi.c:2235 2025-06-14 14:05:28.716770 :A4985293:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 14:05:28.716882 :A498529C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 14632, J002)