Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250727220750\orcl_j000_17608_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:36004M/63366M, Ph+PgF:41761M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 17608, image: ORACLE.EXE (J000) *** 2025-07-27 22:07:50.489 *** SESSION ID:(177.42597) 2025-07-27 22:07:50.489 *** 2025-07-27 22:07:50.489 Process diagnostic dump for ORACLE.EXE (J000), OS id=17608, pid: 22, proc_ser: 203, sid: 177, sess_ser: 42597 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17608 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) 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.165101 sec, exc=0.165101 sec, total=0.165101 sec wait times: max=0.500000 sec, heur=2.158455 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.000007 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.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.500166 sec, exc=0.500166 sec, total=0.500166 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.493155 sec, exc=0.493155 sec, total=0.493155 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 177 serial 42597 --------------------------------------------------- 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:07:48 - 22:07:50] idle wait at each sample [session created at: 22:07:48] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-27 22:07:50.489 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 17608, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-27 22:06:07.273772 :B7D5CA40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:06:27.038011 :B7D5D0AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:06:27.030 2025-07-27 22:06:27.038013 :B7D5D0B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18292 pso_num=22 pso_serial#=190 2025-07-27 22:06:27.038147 :B7D5D0B1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18292 (legacy spawn) 2025-07-27 22:06:27.044616 :B7D5D0B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:27.044795 :B7D5D0B7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:06:27.045594 :B7D5D0BC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:06:27.570274 :B7D5D0D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:06:27.561 2025-07-27 22:06:27.570276 :B7D5D0D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16504 pso_num=22 pso_serial#=191 2025-07-27 22:06:27.570412 :B7D5D0DA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16504 (legacy spawn) 2025-07-27 22:06:27.576899 :B7D5D0DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:27.577072 :B7D5D0E0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:06:27.577929 :B7D5D0E5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:06:36.609347 :B7D5D3AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:06:36.595 2025-07-27 22:06:36.609349 :B7D5D3B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13932 pso_num=22 pso_serial#=192 2025-07-27 22:06:36.609493 :B7D5D3B1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13932 (legacy spawn) 2025-07-27 22:06:36.615950 :B7D5D3B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:36.616119 :B7D5D3B7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:06:36.616908 :B7D5D3BC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:06:36.625781 :B7D5D3BF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:06:36.626 2025-07-27 22:06:36.625784 :B7D5D3C0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18260 pso_num=22 pso_serial#=193 2025-07-27 22:06:36.625920 :B7D5D3C1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18260 (legacy spawn) 2025-07-27 22:06:36.632400 :B7D5D3C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:36.632564 :B7D5D3CB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:06:36.633358 :B7D5D3CC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:06:37.272523 :B7D5D406:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:06:37.266 2025-07-27 22:06:37.272525 :B7D5D407:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13868 pso_num=22 pso_serial#=194 2025-07-27 22:06:37.272665 :B7D5D408:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13868 osp_idx=0 osp_ver=459011 osp_pg=0 (spawn #429171) 2025-07-27 22:06:37.272666 :B7D5D409:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-27 22:06:37.272666 :B7D5D40A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=2334773173 2025-07-27 22:06:37.272667 :B7D5D40B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-27 22:06:37.272667 :B7D5D40C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-27 22:06:37.272668 :B7D5D40D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-27 22:06:37.272668 :B7D5D40E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-27 22:06:37.272668 :B7D5D40F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-27 22:06:37.273176 :B7D5D410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-27 22:06:37.273892 :B7D5D425:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:37.285352 :B7D5D441:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-27 22:06:37.285841 :B7D5D442:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-27 22:06:37.288415 :B7D5D443:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:06:37.288792 :B7D5D444:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:37.288832 :B7D5D447:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:06:37.289780 :B7D5D44D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:06:37.289908 :B7D5D456:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:07.363941 :B7D5DE13:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:13.112594 :B7D5DFD2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:13.099 2025-07-27 22:07:13.112596 :B7D5DFD3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18472 pso_num=22 pso_serial#=195 2025-07-27 22:07:13.112737 :B7D5DFD4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18472 (legacy spawn) 2025-07-27 22:07:13.119144 :B7D5DFD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:13.119327 :B7D5DFDA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:13.120122 :B7D5DFDF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:13.128866 :B7D5DFE2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:13.115 2025-07-27 22:07:13.128868 :B7D5DFE3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15328 pso_num=22 pso_serial#=196 2025-07-27 22:07:13.129003 :B7D5DFE4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15328 (legacy spawn) 2025-07-27 22:07:13.135692 :B7D5DFE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:13.135861 :B7D5DFEE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:13.136717 :B7D5DFEF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:16.208095 :B7D5E0D9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:16.195 2025-07-27 22:07:16.208097 :B7D5E0DA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11292 pso_num=22 pso_serial#=197 2025-07-27 22:07:16.208235 :B7D5E0DB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11292 (legacy spawn) 2025-07-27 22:07:16.214733 :B7D5E0DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:16.214762 :B7D5E0DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:16.214914 :B7D5E0E2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:16.215700 :B7D5E0F3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:16.224508 :B7D5E0F6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:16.211 2025-07-27 22:07:16.224510 :B7D5E0F7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15780 pso_num=22 pso_serial#=198 2025-07-27 22:07:16.224645 :B7D5E0F8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15780 (legacy spawn) 2025-07-27 22:07:16.231088 :B7D5E0F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:16.231257 :B7D5E102:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:16.232065 :B7D5E103:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:27.607619 :B7D5E4E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:27.600 2025-07-27 22:07:27.607621 :B7D5E4E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17888 pso_num=22 pso_serial#=199 2025-07-27 22:07:27.607761 :B7D5E4E9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17888 (legacy spawn) 2025-07-27 22:07:27.614252 :B7D5E4EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:27.614288 :B7D5E4ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:27.614454 :B7D5E4F0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:27.615238 :B7D5E501:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:27.624062 :B7D5E504:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:27.616 2025-07-27 22:07:27.624064 :B7D5E505:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17692 pso_num=22 pso_serial#=200 2025-07-27 22:07:27.624200 :B7D5E506:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17692 (legacy spawn) 2025-07-27 22:07:27.630598 :B7D5E507:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:27.630761 :B7D5E510:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:27.631570 :B7D5E511:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:29.728167 :B7D5E5B1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:29.720 2025-07-27 22:07:29.728169 :B7D5E5B2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14304 pso_num=22 pso_serial#=201 2025-07-27 22:07:29.728306 :B7D5E5B3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14304 (legacy spawn) 2025-07-27 22:07:29.734731 :B7D5E5B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:29.734892 :B7D5E5B9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:29.735831 :B7D5E5BE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:35.337238 :B7D5E76B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:35.328 2025-07-27 22:07:35.337240 :B7D5E76C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8164 pso_num=22 pso_serial#=202 2025-07-27 22:07:35.337430 :B7D5E76D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8164 (legacy spawn) 2025-07-27 22:07:35.344328 :B7D5E76E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:35.344493 :B7D5E773:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:35.345475 :B7D5E778:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-27 22:07:37.368874 :B7D5E833:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-27 22:07:37.362 2025-07-27 22:07:37.368876 :B7D5E834:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17608 pso_num=22 pso_serial#=203 2025-07-27 22:07:37.369011 :B7D5E835:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17608 osp_idx=0 osp_ver=459020 osp_pg=0 (spawn #429173) 2025-07-27 22:07:37.369012 :B7D5E836:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-27 22:07:37.369012 :B7D5E837:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=2334833267 2025-07-27 22:07:37.369013 :B7D5E838:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-27 22:07:37.369013 :B7D5E839:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-27 22:07:37.369013 :B7D5E83A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-27 22:07:37.369013 :B7D5E83B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-27 22:07:37.369014 :B7D5E83C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-27 22:07:37.369500 :B7D5E83D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-27 22:07:37.370127 :B7D5E852:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:37.377814 :B7D5E86E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:37.378162 :B7D5E86F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:37.378196 :B7D5E872:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:37.379203 :B7D5E878:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:37.379337 :B7D5E881:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-27 22:07:46.570800 :B7D5EC84:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-27 22:07:46.676858 :B7D5EFDE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-27 22:07:46.676858*:B7D5EFED:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1753625266 eid=16784992 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-27 22:07:46.676858*:B7D5EFEE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-07-27 22:07:46.676858*:B7D5EFEF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-07-27 22:07:46.676858*:B7D5EFF0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-07-27 22:07:46.676858*:B7D5EFF1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-07-27 22:07:46.676858*:B7D5EFF2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-07-27 22:07:46.681960 :B7D5EFF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:46.681996 :B7D5EFF8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-07-27 22:07:46.685949 :B7D5F010:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:46.792315 :B7D5F018:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:46.920194 :B7D5F020:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:47.046222 :B7D5F028:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.166298 :B7D5F0CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.167268 :B7D5F0D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.168972 :B7D5F0DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.170526 :B7D5F0E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.173052 :B7D5F0EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.174684 :B7D5F0F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.190794 :B7D5F0FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.191469 :B7D5F102:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.192039 :B7D5F10A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.192556 :B7D5F112:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.195269 :B7D5F11A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.196177 :B7D5F122:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.196947 :B7D5F123:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.197705 :B7D5F124:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.198110 :B7D5F125:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.198116 :B7D5F126:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.198757 :B7D5F127:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.199266 :B7D5F128:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.199580 :B7D5F129:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.200642 :B7D5F12A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.230319 :B7D5F12B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.230367 :B7D5F12E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.231253 :B7D5F134:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.232142 :B7D5F13C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.232574 :B7D5F13D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.253446 :B7D5F145:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.253495 :B7D5F148:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.254310 :B7D5F14E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.265853 :B7D5F156:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.266327 :B7D5F157:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.284578 :B7D5F15F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.284635 :B7D5F162:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.285555 :B7D5F168:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.286376 :B7D5F170:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.286798 :B7D5F171:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.305351 :B7D5F183:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.305401 :B7D5F186:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.306290 :B7D5F18C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.307264 :B7D5F194:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-27 22:07:48.307759 :B7D5F195:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.329980 :B7D5F1D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.330030 :B7D5F1D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-27 22:07:48.331019 :B7D5F1DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.331497 :B7D5F1E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.331862 :B7D5F1ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.332040 :B7D5F1F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.332174 :B7D5F1FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.332323 :B7D5F205:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-07-27 22:07:48.336509 :B7D5F206:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.336585 :B7D5F209:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-27 22:07:48.336666 :B7D5F212:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 17608, J000)