Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250619220009\orcl_j000_13728_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:36461M/63366M, Ph+PgF:42834M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 13728, image: ORACLE.EXE (J000) *** 2025-06-19 22:00:09.530 *** SESSION ID:(193.16145) 2025-06-19 22:00:09.530 *** 2025-06-19 22:00:09.530 Process diagnostic dump for ORACLE.EXE (J000), OS id=13728, pid: 24, proc_ser: 154, sid: 193, sess_ser: 16145 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13728 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.315717 sec, exc=0.315717 sec, total=0.315717 sec wait times: max=0.500000 sec, heur=7.356982 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=14 seq_num=15 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=13 seq_num=14 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=12 seq_num=13 snap_id=1 wait times: snap=0.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499978 sec, exc=0.499978 sec, total=0.499978 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 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=6 seq_num=7 snap_id=1 wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.498867 sec, exc=0.498867 sec, total=0.498867 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 193 serial 16145 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:02 - 22:00:09] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-19 22:00:09.530 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 13728, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-19 21:56:08.716423 :A6F22FB5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16200 (legacy spawn) 2025-06-19 21:56:08.723622 :A6F22FB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:08.723787 :A6F22FBF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:56:08.724617 :A6F22FC0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:56:11.915488 :A6F230BA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:56:11.903 2025-06-19 21:56:11.915490 :A6F230BB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10856 pso_num=24 pso_serial#=139 2025-06-19 21:56:11.915620 :A6F230BC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10856 (legacy spawn) 2025-06-19 21:56:11.922061 :A6F230BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:11.922237 :A6F230C6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:56:11.923053 :A6F230C7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:56:11.931615 :A6F230CA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:56:11.918 2025-06-19 21:56:11.931617 :A6F230CB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17180 pso_num=24 pso_serial#=140 2025-06-19 21:56:11.931755 :A6F230CC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17180 (legacy spawn) 2025-06-19 21:56:11.938210 :A6F230CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:11.938374 :A6F230D6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:56:11.939146 :A6F230D7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:56:20.423697 :A6F2335C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:56:20.415 2025-06-19 21:56:20.423699 :A6F2335D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17840 pso_num=24 pso_serial#=141 2025-06-19 21:56:20.423838 :A6F2335E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17840 (legacy spawn) 2025-06-19 21:56:20.430380 :A6F2335F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:20.430551 :A6F23364:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:56:20.431349 :A6F23369:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:56:26.567242 :A6F23543:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:56:26.558 2025-06-19 21:56:26.567245 :A6F23544:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17864 pso_num=24 pso_serial#=142 2025-06-19 21:56:26.567398 :A6F23545:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17864 (legacy spawn) 2025-06-19 21:56:26.573889 :A6F23546:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:26.574078 :A6F2354B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:56:26.574892 :A6F23550:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:56:33.721843 :A6F237B1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:56:33.716 2025-06-19 21:56:33.721846 :A6F237B2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16544 pso_num=24 pso_serial#=143 2025-06-19 21:56:33.721986 :A6F237B3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16544 osp_idx=22 osp_ver=435702 osp_pg=0 (spawn #302475) 2025-06-19 21:56:33.721986 :A6F237B4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 21:56:33.721987 :A6F237B5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=23 time=3345936907 2025-06-19 21:56:33.721987 :A6F237B6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:56:33.721988 :A6F237B7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:56:33.721988 :A6F237B8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:56:33.721988 :A6F237B9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:56:33.721988 :A6F237BA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:56:33.722460 :A6F237BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-19 21:56:33.723089 :A6F237D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:33.733989 :A6F237EC:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-19 21:56:33.734468 :A6F237ED:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-19 21:56:33.736748 :A6F237EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-19 21:56:33.737157 :A6F237EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:33.737190 :A6F237F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-19 21:56:33.738074 :A6F237F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:56:33.738201 :A6F23801:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:03.807631 :A6F241A2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:21.412416 :A6F24716:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:21.409 2025-06-19 21:57:21.412418 :A6F24717:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18344 pso_num=24 pso_serial#=144 2025-06-19 21:57:21.412560 :A6F24718:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18344 (legacy spawn) 2025-06-19 21:57:21.418955 :A6F24719:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:21.419137 :A6F24722:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:21.419928 :A6F24723:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:21.429022 :A6F24726:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:21.425 2025-06-19 21:57:21.429024 :A6F24727:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18116 pso_num=24 pso_serial#=145 2025-06-19 21:57:21.429167 :A6F24728:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18116 (legacy spawn) 2025-06-19 21:57:21.435703 :A6F24729:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:21.435862 :A6F24732:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:21.436668 :A6F24733:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:24.844806 :A6F2483F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:24.841 2025-06-19 21:57:24.844809 :A6F24840:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17884 pso_num=24 pso_serial#=146 2025-06-19 21:57:24.844947 :A6F24841:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17884 (legacy spawn) 2025-06-19 21:57:24.851431 :A6F24842:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:24.851598 :A6F24847:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:24.852421 :A6F2484B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:24.861115 :A6F2484E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:24.857 2025-06-19 21:57:24.861117 :A6F2484F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18200 pso_num=24 pso_serial#=147 2025-06-19 21:57:24.861251 :A6F24850:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18200 (legacy spawn) 2025-06-19 21:57:24.867687 :A6F24851:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:24.867846 :A6F2485A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:24.868642 :A6F2485B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:28.727464 :A6F2498E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:28.719 2025-06-19 21:57:28.727466 :A6F2498F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3836 pso_num=24 pso_serial#=148 2025-06-19 21:57:28.727604 :A6F24990:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3836 (legacy spawn) 2025-06-19 21:57:28.734040 :A6F24991:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:28.734220 :A6F24996:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:28.735167 :A6F2499B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:28.852430 :A6F249B7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:28.844 2025-06-19 21:57:28.852432 :A6F249B8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17676 pso_num=24 pso_serial#=149 2025-06-19 21:57:28.852564 :A6F249B9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17676 (legacy spawn) 2025-06-19 21:57:28.859027 :A6F249BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:28.859216 :A6F249C2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:57:28.860075 :A6F249C4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:57:33.822453 :A6F24B4E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:57:33.816 2025-06-19 21:57:33.822454 :A6F24B4F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3024 pso_num=24 pso_serial#=150 2025-06-19 21:57:33.822586 :A6F24B50:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3024 osp_idx=22 osp_ver=435709 osp_pg=0 (spawn #302477) 2025-06-19 21:57:33.822587 :A6F24B51:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 21:57:33.822587 :A6F24B52:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=3345997016 2025-06-19 21:57:33.822588 :A6F24B53:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:57:33.822588 :A6F24B54:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:57:33.822588 :A6F24B55:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:57:33.822589 :A6F24B56:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:57:33.822589 :A6F24B57:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:57:33.823070 :A6F24B58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-19 21:57:33.823703 :A6F24B6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:33.831196 :A6F24B89:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-19 21:57:33.831541 :A6F24B8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:33.831577 :A6F24B8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-19 21:57:33.832515 :A6F24B93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:57:33.832645 :A6F24B9C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:58:03.924140 :A6F2560B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:58:31.076304 :A6F25E7E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:58:31.067 2025-06-19 21:58:31.076306 :A6F25E7F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13092 pso_num=24 pso_serial#=151 2025-06-19 21:58:31.076442 :A6F25E80:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13092 (legacy spawn) 2025-06-19 21:58:31.083008 :A6F25E81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:58:31.083175 :A6F25E87:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:58:31.084024 :A6F25E8B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:58:33.910982 :A6F25F8A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:58:33.905 2025-06-19 21:58:33.910984 :A6F25F8B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15096 pso_num=24 pso_serial#=152 2025-06-19 21:58:33.911116 :A6F25F8C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15096 osp_idx=22 osp_ver=435711 osp_pg=0 (spawn #302480) 2025-06-19 21:58:33.911117 :A6F25F8D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 21:58:33.911117 :A6F25F8E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=25 time=3346057094 2025-06-19 21:58:33.911117 :A6F25F8F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:58:33.911118 :A6F25F90:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:58:33.911118 :A6F25F91:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:58:33.911118 :A6F25F92:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:58:33.911119 :A6F25F93:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:58:33.911612 :A6F25F94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-19 21:58:33.912304 :A6F25FA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:58:33.923334 :A6F25FC5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-19 21:58:33.923816 :A6F25FC6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-19 21:58:33.926172 :A6F25FC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-19 21:58:33.926521 :A6F25FC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:58:33.926561 :A6F25FCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-19 21:58:33.927421 :A6F25FD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:58:33.927544 :A6F25FDA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:58:58.926617 :A6F2680E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:59:28.984710 :A6F271EC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:59:33.237177 :A6F2731F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:59:33.228 2025-06-19 21:59:33.237179 :A6F27320:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15744 pso_num=24 pso_serial#=153 2025-06-19 21:59:33.237316 :A6F27321:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15744 (legacy spawn) 2025-06-19 21:59:33.243827 :A6F27322:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:59:33.244004 :A6F27327:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 21:59:33.244844 :A6F2732C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 21:59:33.968877 :A6F27383:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 21:59:33.963 2025-06-19 21:59:33.968879 :A6F27384:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13728 pso_num=24 pso_serial#=154 2025-06-19 21:59:33.969017 :A6F27385:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13728 osp_idx=22 osp_ver=435713 osp_pg=0 (spawn #302482) 2025-06-19 21:59:33.969017 :A6F27386:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 21:59:33.969017 :A6F27387:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=26 time=3346117157 2025-06-19 21:59:33.969018 :A6F27388:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 21:59:33.969018 :A6F27389:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 21:59:33.969018 :A6F2738A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 21:59:33.969019 :A6F2738B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 21:59:33.969019 :A6F2738C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 21:59:33.969516 :A6F2738D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-19 21:59:33.970142 :A6F273A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:59:33.977683 :A6F273BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-19 21:59:33.978051 :A6F273BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:59:33.978085 :A6F273C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-19 21:59:33.978930 :A6F273C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 21:59:33.979054 :A6F273D1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 22:00:02.041892 :A6F27F00:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 22:00:02.041892*:A6F27F22:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1750341601 eid=16782641 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-19 22:00:02.041892*:A6F27F23:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-06-19 22:00:02.041892*:A6F27F24:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-06-19 22:00:02.041892*:A6F27F25:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-06-19 22:00:02.041892*:A6F27F3F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-06-19 22:00:02.041892*:A6F27F40:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-06-19 22:00:02.048722 :A6F27F46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.048789 :A6F27F49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-19 22:00:02.061125 :A6F282A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.065974 :A6F282D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.066831 :A6F282DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.072255 :A6F2832C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.072419 :A6F2833E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.073253 :A6F2835E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.073446 :A6F28373:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-19 22:00:02.074847 :A6F283BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.074879 :A6F283C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.074942 :A6F283D0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 22:00:02.167207 :A6F28591:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 22:00:02.169492 :A6F28598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.169532 :A6F2859B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-19 22:00:02.171986 :A6F285A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-19 22:00:02.175391 :A6F285A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.175471 :A6F285A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-19 22:00:02.175560 :A6F285B2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-19 22:00:04.198543 :A6F28A6D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 13728, J000)