Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525100355\orcl_j000_4208_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:37340M/63366M, Ph+PgF:44397M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 4208, image: ORACLE.EXE (J000) *** 2025-05-25 10:03:55.189 *** SESSION ID:(201.61887) 2025-05-25 10:03:55.189 *** 2025-05-25 10:03:55.189 Process diagnostic dump for ORACLE.EXE (J000), OS id=4208, pid: 25, proc_ser: 153, sid: 201, sess_ser: 61887 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4208 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.127871 sec, exc=0.127871 sec, total=0.127871 sec wait times: max=0.500000 sec, heur=5.640630 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=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.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500102 sec, exc=0.500102 sec, total=0.500102 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=7 seq_num=8 snap_id=1 wait times: snap=0.513497 sec, exc=0.513497 sec, total=0.513497 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500153 sec, exc=0.500153 sec, total=0.500153 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 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=3 seq_num=4 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500111 sec, exc=0.500111 sec, total=0.500111 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=1 seq_num=2 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 Sampled Session History of session 201 serial 61887 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 10:03:49 - 10:03:55] idle wait at each sample [session created at: 10:03:49] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 10:03:55.189 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 4208, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 10:02:33.353000 :9BC1A7FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-05-25 10:02:33.365531 :9BC1A805:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.365595 :9BC1A808:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-05-25 10:02:33.365627 :9BC1A809:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-05-25 10:02:33.368300 :9BC1A80F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.368387 :9BC1A810:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.387514 :9BC1A811:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.387572 :9BC1A812:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.387813 :9BC1A813:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.387849 :9BC1A814:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.387898 :9BC1A815:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.388237 :9BC1A816:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.388280 :9BC1A819:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.397233 :9BC1A81F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.397303 :9BC1A822:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-05-25 10:02:33.397336 :9BC1A823:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-05-25 10:02:33.399225 :9BC1A829:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.399277 :9BC1A82A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.399766 :9BC1A82B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.400011 :9BC1A82C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.400049 :9BC1A82F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.405180 :9BC1A835:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.405238 :9BC1A838:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-05-25 10:02:33.405271 :9BC1A839:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-05-25 10:02:33.405562 :9BC1A83F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-25 10:02:33.405885 :9BC1A840:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-25 10:02:33.406063 :9BC1A841:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.406279 :9BC1A842:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.406301 :9BC1A845:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.407948 :9BC1A84B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:02:33.408189 :9BC1A84C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.408219 :9BC1A84F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:02:33.409088 :9BC1A855:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:33.409161 :9BC1A858:db_trace:kcrfw.c@4522:kcrf_commit_force(): [10021:25:201:13] 0000000000000000 2025-05-25 10:02:33.413157 :9BC1A85F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:33.413750 :9BC1A860:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:37.327682 :9BC1A97A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:37.319 2025-05-25 10:02:37.327684 :9BC1A97B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9552 pso_num=25 pso_serial#=140 2025-05-25 10:02:37.327821 :9BC1A97C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9552 (legacy spawn) 2025-05-25 10:02:37.334155 :9BC1A97D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:37.334340 :9BC1A982:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:37.335194 :9BC1A987:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:43.084673 :9BC1AB2B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:43.076 2025-05-25 10:02:43.084675 :9BC1AB2C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15304 pso_num=25 pso_serial#=141 2025-05-25 10:02:43.084872 :9BC1AB2D:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=15304 (legacy spawn) 2025-05-25 10:02:43.091395 :9BC1AB2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:43.091573 :9BC1AB33:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:43.092405 :9BC1AB60:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:43.179334 :9BC1AB63:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:43.170 2025-05-25 10:02:43.179336 :9BC1AB64:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13504 pso_num=25 pso_serial#=142 2025-05-25 10:02:43.179474 :9BC1AB65:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13504 (legacy spawn) 2025-05-25 10:02:43.185971 :9BC1AB66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:43.186135 :9BC1AB6F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:43.186932 :9BC1AB70:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:51.858240 :9BC1AE08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:51.855 2025-05-25 10:02:51.858241 :9BC1AE09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13120 pso_num=25 pso_serial#=143 2025-05-25 10:02:51.858378 :9BC1AE0A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13120 (legacy spawn) 2025-05-25 10:02:51.864954 :9BC1AE0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:51.865133 :9BC1AE10:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:51.865976 :9BC1AE15:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:51.874720 :9BC1AE18:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:51.871 2025-05-25 10:02:51.874722 :9BC1AE19:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14716 pso_num=25 pso_serial#=144 2025-05-25 10:02:51.874859 :9BC1AE1A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14716 (legacy spawn) 2025-05-25 10:02:51.881224 :9BC1AE1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:51.881388 :9BC1AE21:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:51.882201 :9BC1AE25:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:55.054892 :9BC1AF12:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:55.044 2025-05-25 10:02:55.054894 :9BC1AF13:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13268 pso_num=25 pso_serial#=145 2025-05-25 10:02:55.055035 :9BC1AF14:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13268 (legacy spawn) 2025-05-25 10:02:55.061669 :9BC1AF15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:55.061853 :9BC1AF1A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:55.062653 :9BC1AF1F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:02:55.071276 :9BC1AF22:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:02:55.060 2025-05-25 10:02:55.071278 :9BC1AF23:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11296 pso_num=25 pso_serial#=146 2025-05-25 10:02:55.071412 :9BC1AF24:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11296 (legacy spawn) 2025-05-25 10:02:55.077841 :9BC1AF25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:02:55.077995 :9BC1AF2D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:02:55.078776 :9BC1AF2E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:14.161629 :9BC1B54C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:14.153 2025-05-25 10:03:14.161632 :9BC1B54D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14100 pso_num=25 pso_serial#=147 2025-05-25 10:03:14.161772 :9BC1B54E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14100 (legacy spawn) 2025-05-25 10:03:14.168234 :9BC1B54F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:14.168406 :9BC1B554:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:14.169223 :9BC1B559:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:17.301682 :9BC1B64C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:17.289 2025-05-25 10:03:17.301684 :9BC1B64D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15096 pso_num=25 pso_serial#=148 2025-05-25 10:03:17.301821 :9BC1B64E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=15096 (legacy spawn) 2025-05-25 10:03:17.308361 :9BC1B64F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:17.308532 :9BC1B654:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:17.309341 :9BC1B659:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:28.344447 :9BC1B99E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:28.337 2025-05-25 10:03:28.344449 :9BC1B99F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14444 pso_num=25 pso_serial#=149 2025-05-25 10:03:28.344579 :9BC1B9A0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14444 (legacy spawn) 2025-05-25 10:03:28.351018 :9BC1B9A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:28.351186 :9BC1B9A6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:28.352016 :9BC1B9AB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:28.360608 :9BC1B9AE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:28.352 2025-05-25 10:03:28.360610 :9BC1B9AF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14096 pso_num=25 pso_serial#=150 2025-05-25 10:03:28.360746 :9BC1B9B0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14096 (legacy spawn) 2025-05-25 10:03:28.367216 :9BC1B9B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:28.367407 :9BC1B9BA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:28.368239 :9BC1B9BB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:31.555348 :9BC1BA9E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:31.542 2025-05-25 10:03:31.555351 :9BC1BA9F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14392 pso_num=25 pso_serial#=151 2025-05-25 10:03:31.555493 :9BC1BAA0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=14392 (legacy spawn) 2025-05-25 10:03:31.561879 :9BC1BAA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:31.562053 :9BC1BAA6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:31.562857 :9BC1BAAB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:31.571528 :9BC1BAAE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:31.558 2025-05-25 10:03:31.571530 :9BC1BAAF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7672 pso_num=25 pso_serial#=152 2025-05-25 10:03:31.571697 :9BC1BAB0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7672 (legacy spawn) 2025-05-25 10:03:31.578089 :9BC1BAB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:31.578248 :9BC1BABA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:31.579047 :9BC1BABB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-25 10:03:33.328505 :9BC1BB86:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-25 10:03:33.323 2025-05-25 10:03:33.328508 :9BC1BB87:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4208 pso_num=25 pso_serial#=153 2025-05-25 10:03:33.328655 :9BC1BB88:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4208 osp_idx=23 osp_ver=115922 osp_pg=0 (spawn #217509) 2025-05-25 10:03:33.328656 :9BC1BB89:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 10:03:33.328656 :9BC1BB8A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=111 time=1143185579 2025-05-25 10:03:33.328656 :9BC1BB8B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 10:03:33.328657 :9BC1BB8C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 10:03:33.328657 :9BC1BB8D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 10:03:33.328657 :9BC1BB8E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 10:03:33.328658 :9BC1BB8F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 10:03:33.329168 :9BC1BB90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 10:03:33.329831 :9BC1BBA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:33.337421 :9BC1BBC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:03:33.337787 :9BC1BBC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:33.337822 :9BC1BBC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:03:33.338693 :9BC1BBCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:33.338819 :9BC1BBD4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-25 10:03:47.830998 :9BC1C1A1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 10:03:47.925833 :9BC1C4DA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-25 10:03:47.925833*:9BC1C4E9:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDC718 estart=1748138627 eid=16781082 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-25 10:03:47.925833*:9BC1C4EA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 pubTabIdxCnt=0 2025-05-25 10:03:47.925833*:9BC1C4EB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDC718 2025-05-25 10:03:47.925833*:9BC1C4EC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDC718 2025-05-25 10:03:47.925833*:9BC1C4ED:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 xsc->flg4=65792 flags=2 2025-05-25 10:03:47.925833*:9BC1C4EE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDC718 2025-05-25 10:03:47.931069 :9BC1C4F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:47.931142 :9BC1C4FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-05-25 10:03:47.935956 :9BC1C50C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:48.062287 :9BC1C520:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:48.199415 :9BC1C528:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:48.336848 :9BC1C530:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.479922 :9BC1C579:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.480929 :9BC1C581:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.499862 :9BC1C589:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.500481 :9BC1C591:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.500980 :9BC1C599:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.501465 :9BC1C5A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.504251 :9BC1C5A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.505202 :9BC1C5B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:03:49.506001 :9BC1C5B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:03:49.506849 :9BC1C5B3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:03:49.507298 :9BC1C5B4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:03:49.507305 :9BC1C5B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:03:49.507906 :9BC1C5B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:03:49.508412 :9BC1C5B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:03:49.508732 :9BC1C5B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:03:49.509745 :9BC1C5B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-25 10:03:49.541320 :9BC1C5C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.541370 :9BC1C5C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-25 10:03:49.542316 :9BC1C5CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.542819 :9BC1C5D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.543154 :9BC1C5DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.543403 :9BC1C5E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.543548 :9BC1C5EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.543704 :9BC1C5F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-05-25 10:03:49.547691 :9BC1C5F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.547772 :9BC1C5F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-25 10:03:49.547863 :9BC1C604:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 4208, J000)