Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802060006\orcl_j000_16340_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:35828M/63366M, Ph+PgF:41469M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 16340, image: ORACLE.EXE (J000) *** 2025-08-02 06:00:06.597 *** SESSION ID:(177.18545) 2025-08-02 06:00:06.597 *** 2025-08-02 06:00:06.597 Process diagnostic dump for ORACLE.EXE (J000), OS id=16340, pid: 22, proc_ser: 169, sid: 177, sess_ser: 18545 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16340 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.444971 sec, exc=0.444971 sec, total=0.444971 sec wait times: max=0.500000 sec, heur=4.437949 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.502261 sec, exc=0.502261 sec, total=0.502261 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=6 seq_num=7 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.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.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.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.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500137 sec, exc=0.500137 sec, total=0.500137 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.490524 sec, exc=0.490524 sec, total=0.490524 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 18545 --------------------------------------------------- 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, 06:00:02 - 06:00:06] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 06:00:06.597 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 16340, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 05:56:34.136368 :BA339FC3: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-08-02 05:57:04.262552 :BA33A92D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:57:04.786110 :BA33A93B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:57:04.778 2025-08-02 05:57:04.786112 :BA33A93C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20148 pso_num=22 pso_serial#=151 2025-08-02 05:57:04.786245 :BA33A93D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20148 (legacy spawn) 2025-08-02 05:57:04.792625 :BA33A93E: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-08-02 05:57:04.792797 :BA33A943: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-08-02 05:57:04.793628 :BA33A948:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:57:06.076227 :BA33A9FC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:57:06.061 2025-08-02 05:57:06.076229 :BA33A9FD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20464 pso_num=22 pso_serial#=152 2025-08-02 05:57:06.076372 :BA33A9FE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20464 (legacy spawn) 2025-08-02 05:57:06.083047 :BA33A9FF: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-08-02 05:57:06.083228 :BA33AA04: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-08-02 05:57:06.084081 :BA33AA09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:57:06.093318 :BA33AA0C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:57:06.093 2025-08-02 05:57:06.093320 :BA33AA0D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20256 pso_num=22 pso_serial#=153 2025-08-02 05:57:06.093462 :BA33AA0E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20256 (legacy spawn) 2025-08-02 05:57:06.100302 :BA33AA0F: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-08-02 05:57:06.100480 :BA33AA18: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-08-02 05:57:06.101382 :BA33AA19:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:57:09.280337 :BA33AB0E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:57:09.266 2025-08-02 05:57:09.280339 :BA33AB0F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18920 pso_num=22 pso_serial#=154 2025-08-02 05:57:09.280494 :BA33AB10:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18920 (legacy spawn) 2025-08-02 05:57:09.287644 :BA33AB11: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-08-02 05:57:09.287833 :BA33AB16: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-08-02 05:57:09.288761 :BA33AB1B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:57:09.298097 :BA33AB1E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:57:09.297 2025-08-02 05:57:09.298099 :BA33AB1F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4644 pso_num=22 pso_serial#=155 2025-08-02 05:57:09.298252 :BA33AB20:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4644 (legacy spawn) 2025-08-02 05:57:09.304926 :BA33AB21: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-08-02 05:57:09.305115 :BA33AB2A: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-08-02 05:57:09.305933 :BA33AB2B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:57:34.223212 :BA33B319:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:57:34.218 2025-08-02 05:57:34.223215 :BA33B31A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7628 pso_num=22 pso_serial#=156 2025-08-02 05:57:34.223352 :BA33B31B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7628 osp_idx=0 osp_ver=476568 osp_pg=0 (spawn #446904) 2025-08-02 05:57:34.223353 :BA33B31C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:57:34.223353 :BA33B31D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=167 time=2794923533 2025-08-02 05:57:34.223354 :BA33B31E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:57:34.223354 :BA33B31F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:57:34.223355 :BA33B320:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:57:34.223355 :BA33B321:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:57:34.223355 :BA33B322:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:57:34.223855 :BA33B323: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-08-02 05:57:34.224518 :BA33B338: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-08-02 05:57:34.232053 :BA33B354:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-02 05:57:34.232405 :BA33B355: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-08-02 05:57:34.232441 :BA33B358:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-02 05:57:34.233280 :BA33B35E: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-08-02 05:57:34.233394 :BA33B367: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-08-02 05:58:04.294745 :BA33BDCB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:06.948693 :BA33BE89:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:06.939 2025-08-02 05:58:06.948695 :BA33BE8A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16248 pso_num=22 pso_serial#=157 2025-08-02 05:58:06.948880 :BA33BE8B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16248 (legacy spawn) 2025-08-02 05:58:06.955513 :BA33BE8C: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-08-02 05:58:06.955699 :BA33BE91: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-08-02 05:58:06.956514 :BA33BE96:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:09.630674 :BA33BF96:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:09.621 2025-08-02 05:58:09.630677 :BA33BF97:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20164 pso_num=22 pso_serial#=158 2025-08-02 05:58:09.630812 :BA33BF98:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20164 (legacy spawn) 2025-08-02 05:58:09.637299 :BA33BF99: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-08-02 05:58:09.637494 :BA33BF9E: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-08-02 05:58:09.638289 :BA33BFA3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:16.004981 :BA33C18B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:15.996 2025-08-02 05:58:16.004983 :BA33C18C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18744 pso_num=22 pso_serial#=159 2025-08-02 05:58:16.005128 :BA33C18D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18744 (legacy spawn) 2025-08-02 05:58:16.011930 :BA33C18E: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-08-02 05:58:16.012112 :BA33C193: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-08-02 05:58:16.013029 :BA33C198:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:18.764183 :BA33C268:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:18.749 2025-08-02 05:58:18.764185 :BA33C269:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15772 pso_num=22 pso_serial#=160 2025-08-02 05:58:18.764334 :BA33C26A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15772 (legacy spawn) 2025-08-02 05:58:18.771263 :BA33C26B: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-08-02 05:58:18.771445 :BA33C270: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-08-02 05:58:18.772293 :BA33C275:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:18.781322 :BA33C278:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:18.780 2025-08-02 05:58:18.781324 :BA33C279:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18460 pso_num=22 pso_serial#=161 2025-08-02 05:58:18.781464 :BA33C27A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18460 (legacy spawn) 2025-08-02 05:58:18.787987 :BA33C27B: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-08-02 05:58:18.788152 :BA33C284: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-08-02 05:58:18.788987 :BA33C285:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:21.907763 :BA33C37F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:21.895 2025-08-02 05:58:21.907765 :BA33C380:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15660 pso_num=22 pso_serial#=162 2025-08-02 05:58:21.907898 :BA33C381:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15660 (legacy spawn) 2025-08-02 05:58:21.914398 :BA33C382: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-08-02 05:58:21.914567 :BA33C387: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-08-02 05:58:21.915335 :BA33C38C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:21.923960 :BA33C38F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:21.910 2025-08-02 05:58:21.923962 :BA33C390:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12604 pso_num=22 pso_serial#=163 2025-08-02 05:58:21.924099 :BA33C391:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12604 (legacy spawn) 2025-08-02 05:58:21.930628 :BA33C392: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-08-02 05:58:21.930801 :BA33C39B: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-08-02 05:58:21.931640 :BA33C39C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:58:34.310429 :BA33C79B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:58:34.305 2025-08-02 05:58:34.310430 :BA33C79C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19976 pso_num=22 pso_serial#=164 2025-08-02 05:58:34.310574 :BA33C79D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19976 osp_idx=0 osp_ver=476576 osp_pg=0 (spawn #446906) 2025-08-02 05:58:34.310575 :BA33C79E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:58:34.310575 :BA33C79F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=168 time=2794983611 2025-08-02 05:58:34.310576 :BA33C7A0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:58:34.310576 :BA33C7A1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:58:34.310576 :BA33C7A2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:58:34.310577 :BA33C7A3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:58:34.310577 :BA33C7A4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:58:34.311084 :BA33C7A5: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-08-02 05:58:34.311793 :BA33C7BA: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-08-02 05:58:34.322898 :BA33C7D7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-02 05:58:34.323371 :BA33C7D8:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-02 05:58:34.325738 :BA33C7D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-02 05:58:34.326075 :BA33C7DA: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-08-02 05:58:34.326112 :BA33C7DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-02 05:58:34.326992 :BA33C7E3: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-08-02 05:58:34.327129 :BA33C7EC: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-08-02 05:59:04.406159 :BA33D1B8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:59:09.178338 :BA33D354:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:59:09.170 2025-08-02 05:59:09.178340 :BA33D355:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14304 pso_num=22 pso_serial#=165 2025-08-02 05:59:09.178473 :BA33D356:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14304 (legacy spawn) 2025-08-02 05:59:09.184982 :BA33D357: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-08-02 05:59:09.185158 :BA33D35F: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-08-02 05:59:09.186006 :BA33D361:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:59:17.724313 :BA33D5F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:59:17.714 2025-08-02 05:59:17.724315 :BA33D5F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16476 pso_num=22 pso_serial#=166 2025-08-02 05:59:17.724454 :BA33D5F2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16476 (legacy spawn) 2025-08-02 05:59:17.730922 :BA33D5F3: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-08-02 05:59:17.731097 :BA33D5FC: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-08-02 05:59:17.731900 :BA33D5FD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:59:31.670941 :BA33DA31:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:59:31.656 2025-08-02 05:59:31.670943 :BA33DA32:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19728 pso_num=22 pso_serial#=167 2025-08-02 05:59:31.671082 :BA33DA33:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19728 (legacy spawn) 2025-08-02 05:59:31.677557 :BA33DA34: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-08-02 05:59:31.677745 :BA33DA39: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-08-02 05:59:31.678550 :BA33DA3E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:59:31.687259 :BA33DA41:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:59:31.672 2025-08-02 05:59:31.687261 :BA33DA42:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19040 pso_num=22 pso_serial#=168 2025-08-02 05:59:31.687416 :BA33DA43:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19040 (legacy spawn) 2025-08-02 05:59:31.693795 :BA33DA4F: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-08-02 05:59:31.693950 :BA33DA58: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-08-02 05:59:31.694767 :BA33DA59:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 05:59:34.406556 :BA33DB44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 05:59:34.401 2025-08-02 05:59:34.406558 :BA33DB45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16340 pso_num=22 pso_serial#=169 2025-08-02 05:59:34.406691 :BA33DB46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16340 osp_idx=0 osp_ver=476581 osp_pg=0 (spawn #446908) 2025-08-02 05:59:34.406692 :BA33DB47:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:59:34.406693 :BA33DB48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=169 time=2795043704 2025-08-02 05:59:34.406693 :BA33DB49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:59:34.406693 :BA33DB4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:59:34.406694 :BA33DB4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:59:34.406694 :BA33DB4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:59:34.406694 :BA33DB4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:59:34.407170 :BA33DB4E: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-08-02 05:59:34.407892 :BA33DB63: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-08-02 05:59:34.415299 :BA33DB7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-02 05:59:34.415709 :BA33DB80: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-08-02 05:59:34.415745 :BA33DB83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-02 05:59:34.416622 :BA33DB89: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-08-02 05:59:34.416747 :BA33DB92: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-08-02 06:00:02.032447 :BA33E612: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-08-02 06:00:02.032447*:BA33E637:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1754085601 eid=16785307 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-02 06:00:02.032447*:BA33E638:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-08-02 06:00:02.032447*:BA33E639:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-08-02 06:00:02.032447*:BA33E63A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-08-02 06:00:02.032447*:BA33E655:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-08-02 06:00:02.032447*:BA33E656:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-08-02 06:00:02.039329 :BA33E657: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-08-02 06:00:02.039385 :BA33E65A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-08-02 06:00:02.050861 :BA33E7DA: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-08-02 06:00:02.056069 :BA33E807: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-08-02 06:00:02.056888 :BA33E810: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-08-02 06:00:02.062593 :BA33E888:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-02 06:00:02.062731 :BA33E89F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 06:00:02.062959 :BA33E8B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-02 06:00:02.063407 :BA33E8C9: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-08-02 06:00:02.063569 :BA33E8DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-08-02 06:00:02.065024 :BA33E927: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-08-02 06:00:02.065063 :BA33E92A: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-08-02 06:00:02.157287 :BA33E94B: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-08-02 06:00:02.159384 :BA33E94F: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-08-02 06:00:02.159420 :BA33E952:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-08-02 06:00:02.161851 :BA33E958:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-08-02 06:00:02.165121 :BA33E959: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-08-02 06:00:02.165196 :BA33E95C: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-08-02 06:00:02.165296 :BA33E968: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: 16340, J000)