Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250717220006\orcl_j000_3088_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:36252M/63366M, Ph+PgF:42203M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 3088, image: ORACLE.EXE (J000) *** 2025-07-17 22:00:06.711 *** SESSION ID:(193.30791) 2025-07-17 22:00:06.711 *** 2025-07-17 22:00:06.711 Process diagnostic dump for ORACLE.EXE (J000), OS id=3088, pid: 24, proc_ser: 32, sid: 193, sess_ser: 30791 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3088 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.189617 sec, exc=0.189617 sec, total=0.189617 sec wait times: max=0.500000 sec, heur=2.685579 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.000018 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.485815 sec, exc=0.485815 sec, total=0.485815 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 193 serial 30791 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [3 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-17 22:00:06.711 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 3088, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-17 21:57:38.455023 :B3604676:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19320 (legacy spawn) 2025-07-17 21:57:38.461532 :B3604677: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-07-17 21:57:38.461697 :B3604680: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-07-17 21:57:38.462483 :B3604681:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:57:41.637286 :B3604784:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:57:41.630 2025-07-17 21:57:41.637288 :B3604785:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18748 pso_num=24 pso_serial#=16 2025-07-17 21:57:41.637445 :B3604786:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18748 (legacy spawn) 2025-07-17 21:57:41.644217 :B3604787: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-07-17 21:57:41.644418 :B360478C: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-07-17 21:57:41.645263 :B3604791:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:57:41.654834 :B3604794:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:57:41.646 2025-07-17 21:57:41.654837 :B3604795:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18468 pso_num=24 pso_serial#=17 2025-07-17 21:57:41.655008 :B3604796:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18468 (legacy spawn) 2025-07-17 21:57:41.662013 :B3604797: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-07-17 21:57:41.662218 :B36047A0: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-07-17 21:57:41.663146 :B36047A1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:57:54.269823 :B3604BCB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:57:54.264 2025-07-17 21:57:54.269825 :B3604BCC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14100 pso_num=24 pso_serial#=18 2025-07-17 21:57:54.269959 :B3604BCD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14100 osp_idx=22 osp_ver=581683 osp_pg=0 (spawn #395819) 2025-07-17 21:57:54.269959 :B3604BCE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 21:57:54.269960 :B3604BCF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=17 time=1470250158 2025-07-17 21:57:54.269960 :B3604BD0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 21:57:54.269961 :B3604BD1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 21:57:54.269961 :B3604BD2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 21:57:54.269961 :B3604BD3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 21:57:54.269962 :B3604BD4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 21:57:54.270509 :B3604BD5: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-07-17 21:57:54.271240 :B3604BEA: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-07-17 21:57:54.278808 :B3604C06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-17 21:57:54.279158 :B3604C07: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-07-17 21:57:54.279193 :B3604C0A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-17 21:57:54.280076 :B3604C10: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-07-17 21:57:54.280200 :B3604C16: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-07-17 21:58:24.395260 :B3605612:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:37.851578 :B3605A68:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:37.843 2025-07-17 21:58:37.851580 :B3605A69:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19276 pso_num=24 pso_serial#=19 2025-07-17 21:58:37.851724 :B3605A6A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19276 (legacy spawn) 2025-07-17 21:58:37.858211 :B3605A6B: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-07-17 21:58:37.858379 :B3605A73: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-07-17 21:58:37.859223 :B3605A75:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:41.339789 :B3605B91:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:41.331 2025-07-17 21:58:41.339791 :B3605B92:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6572 pso_num=24 pso_serial#=20 2025-07-17 21:58:41.339930 :B3605B93:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6572 (legacy spawn) 2025-07-17 21:58:41.366764 :B3605B94: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-07-17 21:58:41.366937 :B3605B99: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-07-17 21:58:41.367724 :B3605B9E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:51.195013 :B3605E9B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:51.180 2025-07-17 21:58:51.195015 :B3605E9C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13932 pso_num=24 pso_serial#=21 2025-07-17 21:58:51.195147 :B3605E9D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13932 (legacy spawn) 2025-07-17 21:58:51.213434 :B3605E9E: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-07-17 21:58:51.213604 :B3605EA7: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-07-17 21:58:51.214463 :B3605EA8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:51.223094 :B3605EAB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:51.212 2025-07-17 21:58:51.223096 :B3605EAC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12272 pso_num=24 pso_serial#=22 2025-07-17 21:58:51.223229 :B3605EAD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12272 (legacy spawn) 2025-07-17 21:58:51.229729 :B3605EAE: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-07-17 21:58:51.229895 :B3605EB7: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-07-17 21:58:51.230685 :B3605EB8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:54.292247 :B3605FA3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:54.279 2025-07-17 21:58:54.292249 :B3605FA4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11728 pso_num=24 pso_serial#=23 2025-07-17 21:58:54.292390 :B3605FA5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11728 (legacy spawn) 2025-07-17 21:58:54.298790 :B3605FA6: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-07-17 21:58:54.298964 :B3605FAF: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-07-17 21:58:54.299793 :B3605FB0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:54.308422 :B3605FB3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:54.295 2025-07-17 21:58:54.308424 :B3605FB4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19092 pso_num=24 pso_serial#=24 2025-07-17 21:58:54.308557 :B3605FB5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19092 (legacy spawn) 2025-07-17 21:58:54.315927 :B3605FE3: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-07-17 21:58:54.316092 :B3605FEC: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-07-17 21:58:54.316880 :B3605FED:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:58:54.363721 :B3606008:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:58:54.357 2025-07-17 21:58:54.363723 :B3606009:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16492 pso_num=24 pso_serial#=25 2025-07-17 21:58:54.363864 :B360600A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16492 osp_idx=22 osp_ver=581690 osp_pg=0 (spawn #395821) 2025-07-17 21:58:54.363865 :B360600B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 21:58:54.363865 :B360600C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=18 time=1470310251 2025-07-17 21:58:54.363865 :B360600D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 21:58:54.363866 :B360600E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 21:58:54.363866 :B360600F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 21:58:54.363866 :B3606010:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 21:58:54.363867 :B3606011:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 21:58:54.364364 :B3606012: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-07-17 21:58:54.365055 :B3606027: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-07-17 21:58:54.376078 :B3606043:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-17 21:58:54.376544 :B3606044:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-17 21:58:54.378838 :B3606045:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-17 21:58:54.379232 :B3606046: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-07-17 21:58:54.379269 :B3606049:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-17 21:58:54.380150 :B360604F: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-07-17 21:58:54.380273 :B3606058: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-07-17 21:59:24.486127 :B36069F5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:27.391288 :B3606ABE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:27.377 2025-07-17 21:59:27.391290 :B3606ABF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17564 pso_num=24 pso_serial#=26 2025-07-17 21:59:27.391430 :B3606AC0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17564 (legacy spawn) 2025-07-17 21:59:27.398689 :B3606AE4: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-07-17 21:59:27.398861 :B3606AED: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-07-17 21:59:27.399751 :B3606AEE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:27.408332 :B3606AF1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:27.393 2025-07-17 21:59:27.408334 :B3606AF2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18972 pso_num=24 pso_serial#=27 2025-07-17 21:59:27.408476 :B3606AF3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18972 (legacy spawn) 2025-07-17 21:59:27.414952 :B3606AF4: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-07-17 21:59:27.415122 :B3606AFC: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-07-17 21:59:27.415917 :B3606AFE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:30.490490 :B3606BF8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:30.477 2025-07-17 21:59:30.490492 :B3606BF9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10916 pso_num=24 pso_serial#=28 2025-07-17 21:59:30.490640 :B3606BFA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10916 (legacy spawn) 2025-07-17 21:59:30.497073 :B3606BFB: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-07-17 21:59:30.497309 :B3606C04: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-07-17 21:59:30.498094 :B3606C05:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:30.507721 :B3606C08:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:30.493 2025-07-17 21:59:30.507722 :B3606C09:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15356 pso_num=24 pso_serial#=29 2025-07-17 21:59:30.507848 :B3606C0A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15356 (legacy spawn) 2025-07-17 21:59:30.514182 :B3606C0B: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-07-17 21:59:30.514345 :B3606C14: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-07-17 21:59:30.515133 :B3606C15:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:40.074990 :B3606F11:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:40.067 2025-07-17 21:59:40.074992 :B3606F12:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18868 pso_num=24 pso_serial#=30 2025-07-17 21:59:40.075136 :B3606F13:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18868 (legacy spawn) 2025-07-17 21:59:40.081688 :B3606F14: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-07-17 21:59:40.081876 :B3606F19: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-07-17 21:59:40.082836 :B3606F2A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:49.436942 :B360720D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:49.427 2025-07-17 21:59:49.436945 :B360720E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2696 pso_num=24 pso_serial#=31 2025-07-17 21:59:49.437085 :B360720F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2696 (legacy spawn) 2025-07-17 21:59:49.443543 :B3607218: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-07-17 21:59:49.443728 :B360721E: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-07-17 21:59:49.444540 :B3607227:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 21:59:54.464425 :B36073AA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 21:59:54.458 2025-07-17 21:59:54.464427 :B36073AB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3088 pso_num=24 pso_serial#=32 2025-07-17 21:59:54.464563 :B36073AC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3088 osp_idx=22 osp_ver=581697 osp_pg=0 (spawn #395823) 2025-07-17 21:59:54.464564 :B36073AD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 21:59:54.464564 :B36073AE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=19 time=1470370361 2025-07-17 21:59:54.464565 :B36073AF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 21:59:54.464565 :B36073B0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 21:59:54.464566 :B36073B1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 21:59:54.464566 :B36073B2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 21:59:54.464566 :B36073B3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 21:59:54.465063 :B36073B4: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-07-17 21:59:54.465682 :B36073C9: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-07-17 21:59:54.473220 :B36073E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-17 21:59:54.473586 :B36073E6: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-07-17 21:59:54.473622 :B36073E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-17 21:59:54.474417 :B360741C: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-07-17 21:59:54.474534 :B3607424: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-07-17 22:00:02.040436 :B3607886: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-07-17 22:00:02.040436*:B36078AF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1752760801 eid=16784363 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-17 22:00:02.040436*:B36078B0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-07-17 22:00:02.040436*:B36078B1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-07-17 22:00:02.040436*:B36078B2:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-07-17 22:00:02.040436*:B36078C8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-07-17 22:00:02.040436*:B36078C9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-07-17 22:00:02.047204 :B36078CA: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-07-17 22:00:02.047285 :B36078CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-17 22:00:02.058556 :B360795A: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-07-17 22:00:02.063762 :B3607A61: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-07-17 22:00:02.064565 :B3607A76: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-07-17 22:00:02.070087 :B3607A8E: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-07-17 22:00:02.070248 :B3607A9E: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-07-17 22:00:02.071030 :B3607AA6: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-07-17 22:00:02.071320 :B3607AAE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-17 22:00:02.072923 :B3607AC0: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-07-17 22:00:02.072968 :B3607AC3: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-07-17 22:00:02.073069 :B3607AD0: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-07-17 22:00:02.165160 :B3607BD1: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-07-17 22:00:02.167433 :B3607BD6: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-07-17 22:00:02.167480 :B3607BD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-17 22:00:02.170102 :B3607BDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-17 22:00:02.173469 :B3607BE0: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-07-17 22:00:02.173565 :B3607BE3: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-07-17 22:00:02.173657 :B3607BF0: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-07-17 22:00:04.014907 :B3607FF9: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-07-17 22:00:04.017429 :B3607FFB: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-07-17 22:00:04.017485 :B3607FFE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-17 22:00:04.024197 :B3608004: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-07-17 22:00:04.024894 :B360800C: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-07-17 22:00:04.025745 :B3608014: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-07-17 22:00:04.025960 :B360801C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-17 22:00:04.027434 :B360801D: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-07-17 22:00:04.027472 :B3608020: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-07-17 22:00:04.027540 :B360802D: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 3088, J000)