Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250605220008\orcl_j000_15964_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:36839M/63366M, Ph+PgF:43456M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15964, image: ORACLE.EXE (J000) *** 2025-06-05 22:00:08.467 *** SESSION ID:(193.52325) 2025-06-05 22:00:08.467 *** 2025-06-05 22:00:08.467 Process diagnostic dump for ORACLE.EXE (J000), OS id=15964, pid: 24, proc_ser: 50, sid: 193, sess_ser: 52325 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15964 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.359505 sec, exc=0.359505 sec, total=0.359505 sec wait times: max=0.500000 sec, heur=0.849547 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=0 seq_num=1 snap_id=1 wait times: snap=0.490033 sec, exc=0.490033 sec, total=0.490033 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 52325 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:00:08] idle wait at each sample [session created at: 22:00:07] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-05 22:00:08.467 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15964, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-05 21:57:28.955425 :A0CD80EB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:57:32.057781 :A0CD81EE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:57:32.044 2025-06-05 21:57:32.057784 :A0CD81EF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16368 pso_num=24 pso_serial#=33 2025-06-05 21:57:32.057925 :A0CD81F0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16368 (legacy spawn) 2025-06-05 21:57:32.064609 :A0CD81F1: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-05 21:57:32.064790 :A0CD81FA: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-05 21:57:32.065625 :A0CD81FB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:57:32.074465 :A0CD81FE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:57:32.060 2025-06-05 21:57:32.074468 :A0CD81FF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14824 pso_num=24 pso_serial#=34 2025-06-05 21:57:32.074604 :A0CD8200:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14824 (legacy spawn) 2025-06-05 21:57:32.081369 :A0CD8201: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-05 21:57:32.081529 :A0CD820A: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-05 21:57:32.082363 :A0CD820B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:57:32.409143 :A0CD8226:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:57:32.403 2025-06-05 21:57:32.409145 :A0CD8227:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14356 pso_num=24 pso_serial#=35 2025-06-05 21:57:32.409285 :A0CD8228:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14356 osp_idx=22 osp_ver=366999 osp_pg=0 (spawn #255799) 2025-06-05 21:57:32.409285 :A0CD8229: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-05 21:57:32.409285 :A0CD822A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=247 time=2136411063 2025-06-05 21:57:32.409286 :A0CD822B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 21:57:32.409286 :A0CD822C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 21:57:32.409287 :A0CD822D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 21:57:32.409287 :A0CD822E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 21:57:32.409287 :A0CD822F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 21:57:32.409789 :A0CD8230: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-05 21:57:32.410435 :A0CD8245: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-05 21:57:32.418000 :A0CD8261:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-05 21:57:32.418387 :A0CD8262: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-05 21:57:32.418423 :A0CD8265:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-05 21:57:32.419281 :A0CD826B: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-05 21:57:32.419396 :A0CD8273: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-05 21:58:02.524366 :A0CD8BAD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:58:05.242723 :A0CD8CBB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:58:05.228 2025-06-05 21:58:05.242726 :A0CD8CBC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15136 pso_num=24 pso_serial#=36 2025-06-05 21:58:05.242883 :A0CD8CBD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15136 (legacy spawn) 2025-06-05 21:58:05.249355 :A0CD8CBE: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-05 21:58:05.249532 :A0CD8CC7: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-05 21:58:05.250321 :A0CD8CC8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:58:05.259036 :A0CD8CCB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:58:05.244 2025-06-05 21:58:05.259038 :A0CD8CCC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6600 pso_num=24 pso_serial#=37 2025-06-05 21:58:05.259200 :A0CD8CCD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6600 (legacy spawn) 2025-06-05 21:58:05.265656 :A0CD8CCE: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-05 21:58:05.265819 :A0CD8CD7: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-05 21:58:05.266586 :A0CD8CD8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:58:08.352277 :A0CD8DC1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:58:08.340 2025-06-05 21:58:08.352279 :A0CD8DC2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14240 pso_num=24 pso_serial#=38 2025-06-05 21:58:08.352414 :A0CD8DC3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14240 (legacy spawn) 2025-06-05 21:58:08.358981 :A0CD8DC4: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-05 21:58:08.359161 :A0CD8DC9: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-05 21:58:08.359993 :A0CD8DCE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:58:08.368674 :A0CD8DD1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:58:08.356 2025-06-05 21:58:08.368676 :A0CD8DD2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15788 pso_num=24 pso_serial#=39 2025-06-05 21:58:08.368814 :A0CD8DD3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15788 (legacy spawn) 2025-06-05 21:58:08.375363 :A0CD8DD4: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-05 21:58:08.375531 :A0CD8DDD: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-05 21:58:08.376316 :A0CD8DDE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:58:27.486703 :A0CD9335:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:58:27.476 2025-06-05 21:58:27.486705 :A0CD9336:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11720 pso_num=24 pso_serial#=40 2025-06-05 21:58:27.486842 :A0CD9337:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11720 (legacy spawn) 2025-06-05 21:58:27.493421 :A0CD9338: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-05 21:58:27.493612 :A0CD933D: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-05 21:58:27.494396 :A0CD9342:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:58:32.494165 :A0CD950C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:58:32.488 2025-06-05 21:58:32.494167 :A0CD950D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13024 pso_num=24 pso_serial#=41 2025-06-05 21:58:32.494307 :A0CD950E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13024 osp_idx=22 osp_ver=367005 osp_pg=0 (spawn #255802) 2025-06-05 21:58:32.494307 :A0CD950F: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-05 21:58:32.494308 :A0CD9510:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=248 time=2136471141 2025-06-05 21:58:32.494308 :A0CD9511:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 21:58:32.494309 :A0CD9512:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 21:58:32.494309 :A0CD9513:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 21:58:32.494309 :A0CD9514:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 21:58:32.494310 :A0CD9515:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 21:58:32.494825 :A0CD9516: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-05 21:58:32.495500 :A0CD952B: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-05 21:58:32.506754 :A0CD9548:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-05 21:58:32.507246 :A0CD9549:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-05 21:58:32.509802 :A0CD954A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-05 21:58:32.510187 :A0CD954B: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-05 21:58:32.510225 :A0CD954E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-05 21:58:32.511167 :A0CD9554: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-05 21:58:32.511299 :A0CD955D: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-05 21:59:02.621122 :A0CD9EA8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:13.277766 :A0CDA1B8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:13.270 2025-06-05 21:59:13.277768 :A0CDA1B9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12564 pso_num=24 pso_serial#=42 2025-06-05 21:59:13.277904 :A0CDA1BA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12564 (legacy spawn) 2025-06-05 21:59:13.284385 :A0CDA1BB: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-05 21:59:13.284547 :A0CDA1C0: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-05 21:59:13.285411 :A0CDA1C5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:18.137036 :A0CDA362:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:18.134 2025-06-05 21:59:18.137038 :A0CDA363:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10288 pso_num=24 pso_serial#=43 2025-06-05 21:59:18.137176 :A0CDA364:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10288 (legacy spawn) 2025-06-05 21:59:18.143551 :A0CDA365: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-05 21:59:18.143724 :A0CDA36A: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-05 21:59:18.144540 :A0CDA36F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:18.153178 :A0CDA372:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:18.150 2025-06-05 21:59:18.153180 :A0CDA373:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13460 pso_num=24 pso_serial#=44 2025-06-05 21:59:18.153313 :A0CDA374:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13460 (legacy spawn) 2025-06-05 21:59:18.159686 :A0CDA375: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-05 21:59:18.159872 :A0CDA37E: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-05 21:59:18.160680 :A0CDA37F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:21.250806 :A0CDA463:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:21.238 2025-06-05 21:59:21.250808 :A0CDA464:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14484 pso_num=24 pso_serial#=45 2025-06-05 21:59:21.250951 :A0CDA465:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14484 (legacy spawn) 2025-06-05 21:59:21.270426 :A0CDA466: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-05 21:59:21.270607 :A0CDA46B: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-05 21:59:21.271442 :A0CDA470:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:21.280069 :A0CDA473:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:21.269 2025-06-05 21:59:21.280071 :A0CDA474:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15540 pso_num=24 pso_serial#=46 2025-06-05 21:59:21.280211 :A0CDA475:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15540 (legacy spawn) 2025-06-05 21:59:21.286687 :A0CDA476: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-05 21:59:21.286853 :A0CDA47F: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-05 21:59:21.287659 :A0CDA480:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:23.344392 :A0CDA562:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:23.336 2025-06-05 21:59:23.344394 :A0CDA563:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8784 pso_num=24 pso_serial#=47 2025-06-05 21:59:23.344567 :A0CDA564:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8784 (legacy spawn) 2025-06-05 21:59:23.350997 :A0CDA565: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-05 21:59:23.351169 :A0CDA56A: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-05 21:59:23.352050 :A0CDA56F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:32.552328 :A0CDA825:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:32.539 2025-06-05 21:59:32.552331 :A0CDA826:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13928 pso_num=24 pso_serial#=48 2025-06-05 21:59:32.552468 :A0CDA827:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13928 (legacy spawn) 2025-06-05 21:59:32.558957 :A0CDA828: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-05 21:59:32.559135 :A0CDA82D: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-05 21:59:32.559938 :A0CDA832:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:32.568579 :A0CDA835:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:32.554 2025-06-05 21:59:32.568580 :A0CDA836:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15572 pso_num=24 pso_serial#=49 2025-06-05 21:59:32.568716 :A0CDA837:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15572 (legacy spawn) 2025-06-05 21:59:32.575181 :A0CDA838: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-05 21:59:32.575343 :A0CDA841: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-05 21:59:32.576135 :A0CDA842:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-05 21:59:32.591589 :A0CDA85D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-05 21:59:32.585 2025-06-05 21:59:32.591591 :A0CDA85E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15964 pso_num=24 pso_serial#=50 2025-06-05 21:59:32.591727 :A0CDA85F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15964 osp_idx=22 osp_ver=367014 osp_pg=0 (spawn #255804) 2025-06-05 21:59:32.591728 :A0CDA860: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-05 21:59:32.591728 :A0CDA861:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=249 time=2136531235 2025-06-05 21:59:32.591729 :A0CDA862:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 21:59:32.591729 :A0CDA863:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 21:59:32.591729 :A0CDA864:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 21:59:32.591730 :A0CDA865:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 21:59:32.591730 :A0CDA866:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 21:59:32.592229 :A0CDA867: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-05 21:59:32.592934 :A0CDA87C: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-05 21:59:32.600424 :A0CDA898:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-05 21:59:32.600792 :A0CDA899: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-05 21:59:32.600828 :A0CDA89C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-05 21:59:32.601801 :A0CDA8A2: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-05 21:59:32.601924 :A0CDA8AB: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-05 22:00:02.043308 :A0CDB400: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-05 22:00:02.043308*:A0CDB424:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1749132001 eid=16781781 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-05 22:00:02.043308*:A0CDB425:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-06-05 22:00:02.043308*:A0CDB428:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-06-05 22:00:02.043308*:A0CDB42C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-06-05 22:00:02.043308*:A0CDB442:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-06-05 22:00:02.043308*:A0CDB443:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-06-05 22:00:02.050060 :A0CDB444: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-05 22:00:02.050128 :A0CDB447:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-05 22:00:02.061467 :A0CDB4D4: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-05 22:00:02.066749 :A0CDB601: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-05 22:00:02.067619 :A0CDB613: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-05 22:00:02.073195 :A0CDB624: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-05 22:00:02.073349 :A0CDB633: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-05 22:00:02.074103 :A0CDB63B: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-05 22:00:02.074350 :A0CDB64B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-05 22:00:02.075795 :A0CDB65C: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-05 22:00:02.075832 :A0CDB65F: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-05 22:00:02.075916 :A0CDB66C: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-05 22:00:02.168258 :A0CDB747: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-05 22:00:04.194135 :A0CDBB61: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-05 22:00:07.621634 :A0CDBDBA: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-05 22:00:07.621661*:A0CDBDBD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000249557B8 estart=1749132007 eid=17002664 ctx=0x0000000777E33918 cwh=0x00000000249404C0 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-05 22:00:07.621661*:A0CDBDBE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000249557B8 curCtx=0x0000000024954718 pubTabIdxCnt=0 2025-06-05 22:00:07.621661*:A0CDBDBF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000249557B8 2025-06-05 22:00:07.621661*:A0CDBDC0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000249557B8 2025-06-05 22:00:07.621661*:A0CDBDC1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000249557B8 curCtx=0x0000000024954718 xsc->flg4=65792 flags=1 2025-06-05 22:00:07.621661*:A0CDBDC2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000249557B8 2025-06-05 22:00:07.623433 :A0CDBDC3: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-05 22:00:07.628718 :A0CDBDCB: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-05 22:00:07.629446 :A0CDBDD3: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-05 22:00:07.629542 :A0CDBDDC: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: 15964, J000)