Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250819220008\orcl_j001_19404_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:35336M/63366M, Ph+PgF:40533M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 19404, image: ORACLE.EXE (J001) *** 2025-08-19 22:00:08.717 *** SESSION ID:(193.43027) 2025-08-19 22:00:08.717 *** 2025-08-19 22:00:08.717 Process diagnostic dump for ORACLE.EXE (J001), OS id=19404, pid: 24, proc_ser: 232, sid: 193, sess_ser: 43027 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19404 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.236779 sec, exc=0.236779 sec, total=0.236779 sec wait times: max=0.500000 sec, heur=1.226861 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=1 seq_num=2 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.490070 sec, exc=0.490070 sec, total=0.490070 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 43027 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:07 - 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-08-19 22:00:08.717 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 19404, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-19 21:49:51.656554 :C1FBC0ED:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18676 pso_num=24 pso_serial#=221 2025-08-19 21:49:51.656669 :C1FBC0EE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18676 osp_idx=22 osp_ver=755286 osp_pg=0 (spawn #505796) 2025-08-19 21:49:51.656670 :C1FBC0EF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:49:51.656670 :C1FBC0F0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=61 time=25893658 2025-08-19 21:49:51.656671 :C1FBC0F1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:49:51.656671 :C1FBC0F2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:49:51.656671 :C1FBC0F3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:49:51.656672 :C1FBC0F4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:49:51.656672 :C1FBC0F5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:49:51.657192 :C1FBC0F6: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-08-19 21:50:06.675801 :C1FBC61A: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-08-19 21:50:36.796400 :C1FBCF9E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:50:51.749454 :C1FBD463:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:50:51.738 2025-08-19 21:50:51.749456 :C1FBD464:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21220 pso_num=24 pso_serial#=222 2025-08-19 21:50:51.749571 :C1FBD465:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21220 osp_idx=22 osp_ver=755287 osp_pg=0 (spawn #505798) 2025-08-19 21:50:51.749571 :C1FBD466:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:50:51.749572 :C1FBD467:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=62 time=25953752 2025-08-19 21:50:51.749572 :C1FBD468:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:50:51.749572 :C1FBD469:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:50:51.749573 :C1FBD46A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:50:51.749573 :C1FBD46B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:50:51.749573 :C1FBD46C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:50:51.750127 :C1FBD46D: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-08-19 21:51:21.832062 :C1FBDE08:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:51:51.835407 :C1FBE7B1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:51:51.824 2025-08-19 21:51:51.835409 :C1FBE7B2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22376 pso_num=24 pso_serial#=223 2025-08-19 21:51:51.835544 :C1FBE7B3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22376 osp_idx=22 osp_ver=755288 osp_pg=0 (spawn #505800) 2025-08-19 21:51:51.835544 :C1FBE7B4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:51:51.835544 :C1FBE7B5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=63 time=26013846 2025-08-19 21:51:51.835545 :C1FBE7B6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:51:51.835545 :C1FBE7B7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:51:51.835546 :C1FBE7B8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:51:51.835546 :C1FBE7B9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:51:51.835547 :C1FBE7BA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:51:51.836092 :C1FBE7BB: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-08-19 21:52:21.905903 :C1FBF1B8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:52:51.921470 :C1FBFB86:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:52:51.910 2025-08-19 21:52:51.921472 :C1FBFB87:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20016 pso_num=24 pso_serial#=224 2025-08-19 21:52:51.921601 :C1FBFB88:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20016 osp_idx=22 osp_ver=755289 osp_pg=0 (spawn #505802) 2025-08-19 21:52:51.921602 :C1FBFB89:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:52:51.921602 :C1FBFB8A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=64 time=26073924 2025-08-19 21:52:51.921602 :C1FBFB8B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:52:51.921603 :C1FBFB8C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:52:51.921603 :C1FBFB8D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:52:51.921604 :C1FBFB8E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:52:51.921604 :C1FBFB8F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:52:51.922099 :C1FBFB90: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-08-19 21:53:22.019794 :C1FC0506:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:53:22.029576 :C1FC0509:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:53:22.020 2025-08-19 21:53:22.029578 :C1FC050A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18624 pso_num=24 pso_serial#=225 2025-08-19 21:53:22.029743 :C1FC050B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18624 (legacy spawn) 2025-08-19 21:53:22.038277 :C1FC0537: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-08-19 21:53:22.038519 :C1FC053C: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-08-19 21:53:22.039536 :C1FC0541:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:53:51.998713 :C1FC0EC0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:53:51.987 2025-08-19 21:53:51.998716 :C1FC0EC1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20892 pso_num=24 pso_serial#=226 2025-08-19 21:53:51.998840 :C1FC0EC2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20892 osp_idx=22 osp_ver=755291 osp_pg=0 (spawn #505805) 2025-08-19 21:53:51.998841 :C1FC0EC3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:53:51.998841 :C1FC0EC4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=65 time=26134002 2025-08-19 21:53:51.998842 :C1FC0EC5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:53:51.998842 :C1FC0EC6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:53:51.998843 :C1FC0EC7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:53:51.998843 :C1FC0EC8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:53:51.998843 :C1FC0EC9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:53:51.999318 :C1FC0ECA: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-08-19 21:54:22.093426 :C1FC1870:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:54:52.057399 :C1FC220E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:54:52.046 2025-08-19 21:54:52.057402 :C1FC220F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19356 pso_num=24 pso_serial#=227 2025-08-19 21:54:52.057547 :C1FC2210:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19356 osp_idx=22 osp_ver=755292 osp_pg=0 (spawn #505808) 2025-08-19 21:54:52.057548 :C1FC2211:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:54:52.057548 :C1FC2212:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=66 time=26194065 2025-08-19 21:54:52.057549 :C1FC2213:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:54:52.057549 :C1FC2214:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:54:52.057550 :C1FC2215:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:54:52.057550 :C1FC2216:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:54:52.057550 :C1FC2217:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:54:52.058028 :C1FC2218: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-08-19 21:55:22.177667 :C1FC2BE5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:55:52.157712 :C1FC3569:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:55:52.146 2025-08-19 21:55:52.157714 :C1FC356A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8988 pso_num=24 pso_serial#=228 2025-08-19 21:55:52.157852 :C1FC356B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8988 osp_idx=22 osp_ver=755293 osp_pg=0 (spawn #505810) 2025-08-19 21:55:52.157853 :C1FC356C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:55:52.157853 :C1FC356D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=67 time=26254158 2025-08-19 21:55:52.157853 :C1FC356E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:55:52.157854 :C1FC356F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:55:52.157854 :C1FC3570:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:55:52.157855 :C1FC3571:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:55:52.157855 :C1FC3572:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:55:52.158334 :C1FC3573: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-08-19 21:56:22.251905 :C1FC3EE9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:56:52.258633 :C1FC48BB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:56:52.247 2025-08-19 21:56:52.258635 :C1FC48BC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20504 pso_num=24 pso_serial#=229 2025-08-19 21:56:52.258756 :C1FC48BD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20504 osp_idx=22 osp_ver=755294 osp_pg=0 (spawn #505812) 2025-08-19 21:56:52.258757 :C1FC48BE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:56:52.258757 :C1FC48BF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=68 time=26314268 2025-08-19 21:56:52.258758 :C1FC48C0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:56:52.258760 :C1FC48C1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:56:52.258760 :C1FC48C2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:56:52.258761 :C1FC48C3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:56:52.258761 :C1FC48C4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:56:52.259244 :C1FC48C5: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-08-19 21:57:22.329431 :C1FC53A5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:57:52.334946 :C1FC5D4C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:57:52.323 2025-08-19 21:57:52.334949 :C1FC5D4D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21696 pso_num=24 pso_serial#=230 2025-08-19 21:57:52.335080 :C1FC5D4E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21696 osp_idx=22 osp_ver=755295 osp_pg=0 (spawn #505814) 2025-08-19 21:57:52.335081 :C1FC5D4F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:57:52.335081 :C1FC5D50:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=69 time=26374346 2025-08-19 21:57:52.335081 :C1FC5D51:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:57:52.335082 :C1FC5D52:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:57:52.335082 :C1FC5D53:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:57:52.335083 :C1FC5D54:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:57:52.335083 :C1FC5D55:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:57:52.335600 :C1FC5D56: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-08-19 21:58:22.407205 :C1FC66B2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:58:52.427442 :C1FC705A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:58:52.416 2025-08-19 21:58:52.427443 :C1FC705B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21312 pso_num=24 pso_serial#=231 2025-08-19 21:58:52.427558 :C1FC705C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21312 osp_idx=22 osp_ver=755296 osp_pg=0 (spawn #505816) 2025-08-19 21:58:52.427559 :C1FC705D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:58:52.427559 :C1FC705E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=70 time=26434424 2025-08-19 21:58:52.427559 :C1FC705F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:58:52.427560 :C1FC7060:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:58:52.427560 :C1FC7061:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:58:52.427561 :C1FC7062:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:58:52.427561 :C1FC7063:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:58:52.428055 :C1FC7064: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-08-19 21:59:22.522561 :C1FC7A47:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-19 21:59:52.483267 :C1FC83FD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-19 21:59:52.471 2025-08-19 21:59:52.483269 :C1FC83FE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19404 pso_num=24 pso_serial#=232 2025-08-19 21:59:52.483382 :C1FC83FF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19404 osp_idx=22 osp_ver=755297 osp_pg=0 (spawn #505819) 2025-08-19 21:59:52.483382 :C1FC8400:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-19 21:59:52.483383 :C1FC8401:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=71 time=26494487 2025-08-19 21:59:52.483383 :C1FC8402:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-19 21:59:52.483384 :C1FC8403:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-19 21:59:52.483384 :C1FC8404:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-19 21:59:52.483384 :C1FC8405:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-19 21:59:52.483384 :C1FC8406:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-19 21:59:52.483880 :C1FC8407: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-08-19 22:00:02.041468 :C1FC88C9: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-08-19 22:00:02.041468*:C1FC88D8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5B898 estart=1755612001 eid=16786396 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-19 22:00:02.041468*:C1FC88D9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 pubTabIdxCnt=0 2025-08-19 22:00:02.041468*:C1FC88DA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5B898 2025-08-19 22:00:02.041468*:C1FC88DB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5B898 2025-08-19 22:00:02.041468*:C1FC88DC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 xsc->flg4=65792 flags=2 2025-08-19 22:00:02.041468*:C1FC88DD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5B898 2025-08-19 22:00:02.047156 :C1FC88EC: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-08-19 22:00:02.047240 :C1FC88F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-19 22:00:02.064633 :C1FC898C:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-19 22:00:02.065301 :C1FC899E:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 538b120000000000 2025-08-19 22:00:02.067038 :C1FC89A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.067125 :C1FC89B4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.067134 :C1FC89BB:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-08-19 22:00:02.067139 :C1FC89BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.067173 :C1FC89C8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.067872 :C1FC89DC:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 234942694) 2025-08-19 22:00:02.067876 :C1FC89DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.067901 :C1FC89ED:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.067910 :C1FC89F5:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1) 2025-08-19 22:00:02.067912 :C1FC89F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.067942 :C1FC8A02:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.067945 :C1FC8A0A:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560) 2025-08-19 22:00:02.067947 :C1FC8A0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.068000 :C1FC8A3B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.068881 :C1FC8AEA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.068897 :C1FC8AEB:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-19 22:00:02.069762 :C1FC8AF2:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 548b120000000000 2025-08-19 22:00:02.070132 :C1FC8AF6:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 234942694) 2025-08-19 22:00:02.070137 :C1FC8AF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-19 22:00:02.070169 :C1FC8B00:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-19 22:00:02.079012 :C1FC8C0E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-19 22:00:02.080752 :C1FC8C0F: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-08-19 22:00:02.080808 :C1FC8C14: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-08-19 22:00:02.080924 :C1FC8C21: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-08-19 22:00:02.166538 :C1FC8C82: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-08-19 22:00:04.019609 :C1FC9063: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-08-19 22:00:07.482919 :C1FC92D6: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-08-19 22:00:07.482940*:C1FC92D8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D557B8 estart=1755612007 eid=17240412 ctx=0x0000000777E33918 cwh=0x0000000024D404C0 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-19 22:00:07.482940*:C1FC92D9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D557B8 curCtx=0x0000000024D54718 pubTabIdxCnt=0 2025-08-19 22:00:07.482940*:C1FC92DA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D557B8 2025-08-19 22:00:07.482940*:C1FC92DB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D557B8 2025-08-19 22:00:07.482940*:C1FC92DC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D557B8 curCtx=0x0000000024D54718 xsc->flg4=65792 flags=1 2025-08-19 22:00:07.482940*:C1FC92DD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D557B8 2025-08-19 22:00:07.484744 :C1FC92DE: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-08-19 22:00:07.490159 :C1FC92E6: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-08-19 22:00:07.491061 :C1FC92EE: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-08-19 22:00:07.491184 :C1FC92F7: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: 19404, J001)