Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250822220005\orcl_j004_21664_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:35297M/63366M, Ph+PgF:40456M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 21664, image: ORACLE.EXE (J004) *** 2025-08-22 22:00:05.240 *** SESSION ID:(241.4209) 2025-08-22 22:00:05.240 *** 2025-08-22 22:00:05.240 Process diagnostic dump for ORACLE.EXE (J004), OS id=21664, pid: 30, proc_ser: 182, sid: 241, sess_ser: 4209 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21664 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) 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.293764 sec, exc=0.293764 sec, total=0.293764 sec wait times: max=0.500000 sec, heur=2.791762 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.000008 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.500003 sec, exc=0.500003 sec, total=0.500003 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=3 seq_num=4 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.510124 sec, exc=0.510124 sec, total=0.510124 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 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.487839 sec, exc=0.487839 sec, total=0.487839 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 241 serial 4209 --------------------------------------------------- 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:02 - 22:00:05] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-22 22:00:05.240 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 21664, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-22 00:04:43.731577 :C2E62416:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:04:46.794219 :C2E62530:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:04:46.781 2025-08-22 00:04:46.794221 :C2E62531:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=14024 pso_num=30 pso_serial#=171 2025-08-22 00:04:46.794358 :C2E62532:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=14024 (legacy spawn) 2025-08-22 00:04:46.800923 :C2E62533:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:04:46.801124 :C2E62538:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:04:46.801912 :C2E6253D:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:04:46.810680 :C2E62540:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:04:46.797 2025-08-22 00:04:46.810683 :C2E62541:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=21948 pso_num=30 pso_serial#=172 2025-08-22 00:04:46.810821 :C2E62542:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=21948 (legacy spawn) 2025-08-22 00:04:46.817296 :C2E62543:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:04:46.817460 :C2E6254C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:04:46.818254 :C2E6254D:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:04:47.554982 :C2E62593:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:04:47.547 2025-08-22 00:04:47.554984 :C2E62594:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20260 pso_num=30 pso_serial#=173 2025-08-22 00:04:47.555120 :C2E62595:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20260 (legacy spawn) 2025-08-22 00:04:47.561647 :C2E62596:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:04:47.561820 :C2E6259E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:04:47.562660 :C2E625A0:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:04:53.325762 :C2E62752:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:04:53.317 2025-08-22 00:04:53.325764 :C2E62753:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=22116 pso_num=30 pso_serial#=174 2025-08-22 00:04:53.325905 :C2E62754:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=22116 (legacy spawn) 2025-08-22 00:04:53.332490 :C2E62755:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:04:53.332672 :C2E6275E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:04:53.333492 :C2E6275F:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:05:49.809399 :C2E63AA6:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:05:49.801 2025-08-22 00:05:49.809401 :C2E63AA7:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=19784 pso_num=30 pso_serial#=175 2025-08-22 00:05:49.809551 :C2E63AA8:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=19784 (legacy spawn) 2025-08-22 00:05:49.816313 :C2E63AA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:05:49.816622 :C2E63AAE:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:05:49.817606 :C2E63AB3:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:05:56.299121 :C2E63CA2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:05:56.284 2025-08-22 00:05:56.299123 :C2E63CA3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=20708 pso_num=30 pso_serial#=176 2025-08-22 00:05:56.299263 :C2E63CA4:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=20708 (legacy spawn) 2025-08-22 00:05:56.305698 :C2E63CA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:05:56.305873 :C2E63CAE:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:05:56.306663 :C2E63CAF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:05:56.315412 :C2E63CB2:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:05:56.300 2025-08-22 00:05:56.315414 :C2E63CB3:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=22216 pso_num=30 pso_serial#=177 2025-08-22 00:05:56.315581 :C2E63CB4:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=22216 (legacy spawn) 2025-08-22 00:05:56.321996 :C2E63CB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:05:56.322176 :C2E63CBE:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:05:56.322979 :C2E63CBF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:05:58.059780 :C2E63D8A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:05:58.054 2025-08-22 00:05:58.059782 :C2E63D8B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=22008 pso_num=30 pso_serial#=178 2025-08-22 00:05:58.059925 :C2E63D8C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=22008 osp_idx=28 osp_ver=1962 osp_pg=0 (spawn #512776) 2025-08-22 00:05:58.059926 :C2E63D8D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-22 00:05:58.059927 :C2E63D8E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=248 time=206860065 2025-08-22 00:05:58.059927 :C2E63D8F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-08-22 00:05:58.059927 :C2E63D90:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-08-22 00:05:58.059927 :C2E63D91:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-08-22 00:05:58.059928 :C2E63D92:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-22 00:05:58.059928 :C2E63D93:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-22 00:05:58.060425 :C2E63D94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-22 00:05:58.060473 :C2E63D9A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-22 00:05:58.064615 :C2E63DC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-08-22 00:05:58.064655 :C2E63DC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-08-22 00:05:58.064916 :C2E63DC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-08-22 00:05:58.064932 :C2E63DC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-08-22 00:05:58.065144 :C2E63DC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-08-22 00:05:58.065158 :C2E63DC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-08-22 00:05:58.065366 :C2E63DC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-08-22 00:05:58.065378 :C2E63DCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-08-22 00:05:58.065579 :C2E63DCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-08-22 00:05:58.065592 :C2E63DCC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-08-22 00:05:58.065795 :C2E63DCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-08-22 00:05:58.065808 :C2E63DCE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-08-22 00:05:58.068154 :C2E63DCF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:05:59.509304 :C2E63DF1:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:05:59.508 2025-08-22 00:05:59.509306 :C2E63DF2:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=22484 pso_num=30 pso_serial#=179 2025-08-22 00:05:59.509441 :C2E63DF3:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=22484 (legacy spawn) 2025-08-22 00:05:59.515604 :C2E63DF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:05:59.515806 :C2E63DFC:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:05:59.516526 :C2E63DFE:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 00:05:59.527289 :C2E63E01:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 00:05:59.523 2025-08-22 00:05:59.527291 :C2E63E02:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18840 pso_num=30 pso_serial#=180 2025-08-22 00:05:59.527425 :C2E63E03:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=18840 (legacy spawn) 2025-08-22 00:05:59.533925 :C2E63E04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 00:05:59.534095 :C2E63E0D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-22 00:05:59.534918 :C2E63E0E:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 01:00:06.427874 :C2EA6E2F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 01:00:06.423 2025-08-22 01:00:06.427876 :C2EA6E30:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11796 pso_num=30 pso_serial#=181 2025-08-22 01:00:06.428016 :C2EA6E31:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11796 osp_idx=28 osp_ver=1965 osp_pg=0 (spawn #512900) 2025-08-22 01:00:06.428017 :C2EA6E32:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-22 01:00:06.428017 :C2EA6E33:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=249 time=210108424 2025-08-22 01:00:06.428017 :C2EA6E34:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-08-22 01:00:06.428018 :C2EA6E35:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-08-22 01:00:06.428018 :C2EA6E36:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-08-22 01:00:06.428019 :C2EA6E37:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-22 01:00:06.428020 :C2EA6E38:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-22 01:00:06.428517 :C2EA6E39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.428564 :C2EA6E3F:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-22 01:00:06.435865 :C2EA6E4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.436930 :C2EA6E55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.437494 :C2EA6E5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.437953 :C2EA6E65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.438456 :C2EA6E6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.440906 :C2EA6E75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.441993 :C2EA6E7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.493039 :C2EA6E85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.493945 :C2EA6E8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.494184 :C2EA6E95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.494406 :C2EA6E9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 01:00:06.496148 :C2EA6EA5:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-08-22 22:00:00.549707 :C34CC775:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 08-22 22:00:00.547 2025-08-22 22:00:00.549708 :C34CC776:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=21664 pso_num=30 pso_serial#=182 2025-08-22 22:00:00.549841 :C34CC77C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=21664 osp_idx=28 osp_ver=1966 osp_pg=0 (spawn #515812) 2025-08-22 22:00:00.549842 :C34CC77D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-22 22:00:00.549842 :C34CC77E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=250 time=285702549 2025-08-22 22:00:00.549843 :C34CC77F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-08-22 22:00:00.549843 :C34CC780:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-08-22 22:00:00.549843 :C34CC781:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-08-22 22:00:00.549844 :C34CC782:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-22 22:00:00.549844 :C34CC783:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-22 22:00:00.550389 :C34CC784:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-22 22:00:00.555664 :C34CC8E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:00.555695 :C34CC8E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-08-22 22:00:00.561395 :C34CC906:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:00.781128 :C34CCA50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:00.979778 :C34CCA68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:01.110587 :C34CCAA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.235759 :C34CCD0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.236745 :C34CCD13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.240704 :C34CCD1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.243921 :C34CCD23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.246289 :C34CCD2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.247766 :C34CCD33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.249365 :C34CCD3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.270702 :C34CCD4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.271389 :C34CCD55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.271933 :C34CCD5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.272448 :C34CCD65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.275171 :C34CCD6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.276077 :C34CCD75:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.276986 :C34CCD7E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.277762 :C34CCD7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.278157 :C34CCD80:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.278164 :C34CCD81:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.278780 :C34CCD84:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.279293 :C34CCD85:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.279603 :C34CCD8A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.280713 :C34CCDA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.320374 :C34CCDB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.320423 :C34CCDB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.321338 :C34CCDBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.322238 :C34CCDC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.322730 :C34CCDC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.343473 :C34CCDCF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.343528 :C34CCDD2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.344454 :C34CCDD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.345327 :C34CCDE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.345759 :C34CCDE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.364949 :C34CCDE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.364996 :C34CCDEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.365876 :C34CCDF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.366786 :C34CCDFA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.367340 :C34CCDFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.389107 :C34CCE03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.389156 :C34CCE06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.390020 :C34CCE0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.390948 :C34CCE14:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.391447 :C34CCE15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.411950 :C34CCE1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.412000 :C34CCE20:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.412796 :C34CCE26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.424579 :C34CCE36:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-08-22 22:00:02.425164 :C34CCE37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.443449 :C34CCE3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.443513 :C34CCE42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-08-22 22:00:02.444386 :C34CCE48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.444923 :C34CCE50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.445343 :C34CCE58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.445529 :C34CCE60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.445666 :C34CCE68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.445809 :C34CCE70:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-08-22 22:00:02.449591 :C34CCE71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.449675 :C34CCE74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-22 22:00:02.449774 :C34CCE81:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 21664, J004)