Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250530220006\orcl_j000_15252_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:37248M/63366M, Ph+PgF:44150M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15252, image: ORACLE.EXE (J000) *** 2025-05-30 22:00:06.567 *** SESSION ID:(193.63647) 2025-05-30 22:00:06.567 *** 2025-05-30 22:00:06.567 Process diagnostic dump for ORACLE.EXE (J000), OS id=15252, pid: 24, proc_ser: 73, sid: 193, sess_ser: 63647 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15252 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.030271 sec, exc=0.030271 sec, total=0.030271 sec wait times: max=0.500000 sec, heur=2.543021 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.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.000010 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.500002 sec, exc=0.500002 sec, total=0.500002 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.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.513099 sec, exc=0.513099 sec, total=0.513099 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.499614 sec, exc=0.499614 sec, total=0.499614 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 63647 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [3 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-30 22:00:06.567 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15252, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-30 21:51:51.229294 :9E2B3301:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:52:21.281205 :9E2B3CDA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:52:21.270 2025-05-30 21:52:21.281207 :9E2B3CDB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8164 pso_num=24 pso_serial#=64 2025-05-30 21:52:21.281331 :9E2B3CDC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8164 osp_idx=22 osp_ver=343823 osp_pg=0 (spawn #235788) 2025-05-30 21:52:21.281332 :9E2B3CDD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:52:21.281332 :9E2B3CDE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=220 time=1617699922 2025-05-30 21:52:21.281332 :9E2B3CDF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:52:21.281333 :9E2B3CE0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:52:21.281333 :9E2B3CE1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:52:21.281334 :9E2B3CE2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:52:21.281334 :9E2B3CE3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:52:21.281844 :9E2B3CE4: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-05-30 21:52:51.381396 :9E2B46B1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:53:21.371965 :9E2B505D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:53:21.361 2025-05-30 21:53:21.371967 :9E2B505E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10868 pso_num=24 pso_serial#=65 2025-05-30 21:53:21.372108 :9E2B505F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10868 osp_idx=22 osp_ver=343824 osp_pg=0 (spawn #235790) 2025-05-30 21:53:21.372109 :9E2B5060:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:53:21.372109 :9E2B5061:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=221 time=1617760016 2025-05-30 21:53:21.372110 :9E2B5062:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:53:21.372110 :9E2B5063:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:53:21.372111 :9E2B5064:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:53:21.372111 :9E2B5065:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:53:21.372111 :9E2B5066:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:53:21.372606 :9E2B5067: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-05-30 21:53:51.474411 :9E2B5A79:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:54:21.460815 :9E2B6410:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:54:21.450 2025-05-30 21:54:21.460818 :9E2B6411:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10896 pso_num=24 pso_serial#=66 2025-05-30 21:54:21.460954 :9E2B6412:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10896 osp_idx=22 osp_ver=343825 osp_pg=0 (spawn #235792) 2025-05-30 21:54:21.460954 :9E2B6413:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:54:21.460955 :9E2B6414:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=222 time=1617820094 2025-05-30 21:54:21.460955 :9E2B6415:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:54:21.460955 :9E2B6416:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:54:21.460955 :9E2B6417:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:54:21.460956 :9E2B6418:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:54:21.460957 :9E2B6419:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:54:21.461470 :9E2B641A: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-05-30 21:54:51.551146 :9E2B6D99:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:55:21.536819 :9E2B7742:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:55:21.525 2025-05-30 21:55:21.536821 :9E2B7743:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14120 pso_num=24 pso_serial#=67 2025-05-30 21:55:21.536957 :9E2B7744:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14120 osp_idx=22 osp_ver=343826 osp_pg=0 (spawn #235794) 2025-05-30 21:55:21.536958 :9E2B7745:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:55:21.536958 :9E2B7746:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=223 time=1617880172 2025-05-30 21:55:21.536958 :9E2B7747:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:55:21.536959 :9E2B7748:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:55:21.536959 :9E2B7749:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:55:21.536960 :9E2B774A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:55:21.536960 :9E2B774B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:55:21.537473 :9E2B774C: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-05-30 21:55:51.590401 :9E2B81C7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:56:21.632197 :9E2B8B68:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:56:21.621 2025-05-30 21:56:21.632199 :9E2B8B69:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14444 pso_num=24 pso_serial#=68 2025-05-30 21:56:21.632335 :9E2B8B6A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14444 osp_idx=22 osp_ver=343827 osp_pg=0 (spawn #235796) 2025-05-30 21:56:21.632335 :9E2B8B6B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:56:21.632336 :9E2B8B6C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=224 time=1617940266 2025-05-30 21:56:21.632336 :9E2B8B6D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:56:21.632336 :9E2B8B6E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:56:21.632337 :9E2B8B6F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:56:21.632337 :9E2B8B70:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:56:21.632337 :9E2B8B71:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:56:21.632813 :9E2B8B72: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-05-30 21:56:51.728041 :9E2B953D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:56:56.705106 :9E2B96B2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:56:56.693 2025-05-30 21:56:56.705108 :9E2B96B3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13660 pso_num=24 pso_serial#=69 2025-05-30 21:56:56.705246 :9E2B96B4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13660 osp_idx=22 osp_ver=343828 osp_pg=0 (spawn #235798) 2025-05-30 21:56:56.705246 :9E2B96B5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:56:56.705247 :9E2B96B6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=225 time=1617975344 2025-05-30 21:56:56.705247 :9E2B96B7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:56:56.705248 :9E2B96B8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:56:56.705248 :9E2B96B9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:56:56.705249 :9E2B96BA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:56:56.705249 :9E2B96BB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:56:56.705741 :9E2B96BC: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-05-30 21:57:21.718340 :9E2B9EB6: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-05-30 21:57:51.827857 :9E2BA894:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:58:21.834003 :9E2BB22A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:58:21.823 2025-05-30 21:58:21.834005 :9E2BB22B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14084 pso_num=24 pso_serial#=70 2025-05-30 21:58:21.834136 :9E2BB22C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14084 osp_idx=22 osp_ver=343829 osp_pg=0 (spawn #235800) 2025-05-30 21:58:21.834136 :9E2BB22D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:58:21.834137 :9E2BB22E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=226 time=1618060469 2025-05-30 21:58:21.834137 :9E2BB22F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:58:21.834137 :9E2BB230:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:58:21.834138 :9E2BB231:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:58:21.834138 :9E2BB232:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:58:21.834138 :9E2BB233:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:58:21.834621 :9E2BB234: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-05-30 21:58:51.919285 :9E2BBC39:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 21:59:21.909600 :9E2BC5EE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 21:59:21.898 2025-05-30 21:59:21.909602 :9E2BC5EF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14532 pso_num=24 pso_serial#=71 2025-05-30 21:59:21.909744 :9E2BC5F0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14532 osp_idx=22 osp_ver=343830 osp_pg=0 (spawn #235802) 2025-05-30 21:59:21.909745 :9E2BC5F1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 21:59:21.909745 :9E2BC5F2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=227 time=1618120547 2025-05-30 21:59:21.909746 :9E2BC5F3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 21:59:21.909746 :9E2BC5F4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 21:59:21.909746 :9E2BC5F5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 21:59:21.909747 :9E2BC5F6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 21:59:21.909747 :9E2BC5F7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 21:59:21.910248 :9E2BC5F8: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-05-30 21:59:51.987105 :9E2BCFDA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 22:00:00.046716 :9E2BD287:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 22:00:00.038 2025-05-30 22:00:00.046718 :9E2BD288:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3448 pso_num=24 pso_serial#=72 2025-05-30 22:00:00.046857 :9E2BD289:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3448 osp_idx=22 osp_ver=343831 osp_pg=0 (spawn #235805) 2025-05-30 22:00:00.046859 :9E2BD28A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 22:00:00.046859 :9E2BD28B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=228 time=1618158688 2025-05-30 22:00:00.046859 :9E2BD28C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 22:00:00.046860 :9E2BD28D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 22:00:00.046860 :9E2BD28E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 22:00:00.046860 :9E2BD28F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 22:00:00.046861 :9E2BD290:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 22:00:00.047367 :9E2BD291:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-30 22:00:00.047412 :9E2BD297:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-05-30 22:00:00.047493 :9E2BD2AB: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-05-30 22:00:00.051435 :9E2BD2BA: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-05-30 22:00:00.052679 :9E2BD2C2: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-05-30 22:00:00.052709 :9E2BD2C5: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-05-30 22:00:00.052771 :9E2BD2D0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-30 22:00:00.054635 :9E2BD2DB: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-05-30 22:00:00.054663 :9E2BD2DE: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-05-30 22:00:00.054703 :9E2BD2E9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-30 22:00:00.055158 :9E2BD2F4: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-05-30 22:00:00.057826 :9E2BD2FC: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-05-30 22:00:00.063239 :9E2BD304: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-05-30 22:00:00.068972 :9E2BD30C: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-05-30 22:00:00.070374 :9E2BD314: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-05-30 22:00:00.073654 :9E2BD31C: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-05-30 22:00:00.074974 :9E2BD324: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-05-30 22:00:00.077973 :9E2BD32C: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-05-30 22:00:00.081395 :9E2BD334: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-05-30 22:00:00.081811 :9E2BD343: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-05-30 22:00:00.081858 :9E2BD346:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-05-30 22:00:00.081873 :9E2BD347:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-05-30 22:00:00.083859 :9E2BD34D: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-05-30 22:00:00.083903 :9E2BD350:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-05-30 22:00:00.083915 :9E2BD351:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-05-30 22:00:00.086229 :9E2BD357: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-05-30 22:00:00.086273 :9E2BD35A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-05-30 22:00:00.086285 :9E2BD35B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-05-30 22:00:00.086444 :9E2BD361: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-05-30 22:00:00.086459 :9E2BD364: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-05-30 22:00:00.086497 :9E2BD36F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-30 22:00:00.087990 :9E2BD37A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-30 22:00:01.966700 :9E2BD446:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-30 22:00:01.960 2025-05-30 22:00:01.966702 :9E2BD447:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15252 pso_num=24 pso_serial#=73 2025-05-30 22:00:01.966842 :9E2BD448:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15252 osp_idx=22 osp_ver=343832 osp_pg=0 (spawn #235806) 2025-05-30 22:00:01.966844 :9E2BD449:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-30 22:00:01.966844 :9E2BD44A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=229 time=1618160610 2025-05-30 22:00:01.966845 :9E2BD44B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-30 22:00:01.966845 :9E2BD44C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-30 22:00:01.966845 :9E2BD44D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-30 22:00:01.966846 :9E2BD44E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-30 22:00:01.966846 :9E2BD44F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-30 22:00:01.967361 :9E2BD450: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-05-30 22:00:01.972860 :9E2BD47D: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-05-30 22:00:01.972908 :9E2BD487:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-30 22:00:01.983993 :9E2BD4D0: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-05-30 22:00:01.989213 :9E2BD4F7: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-05-30 22:00:01.989979 :9E2BD515: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-05-30 22:00:01.995539 :9E2BD551: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-05-30 22:00:01.995703 :9E2BD560: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-05-30 22:00:01.996398 :9E2BD573: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-05-30 22:00:01.996680 :9E2BD57C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-30 22:00:01.998113 :9E2BD57D: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-05-30 22:00:01.998158 :9E2BD580: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-05-30 22:00:01.998228 :9E2BD592: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-05-30 22:00:02.101985 :9E2BD7E0: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-05-30 22:00:02.104083 :9E2BD7E3: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-05-30 22:00:02.104128 :9E2BD7E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-30 22:00:02.106548 :9E2BD7EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-30 22:00:02.109761 :9E2BD7ED: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-05-30 22:00:02.109838 :9E2BD7F0: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-05-30 22:00:02.109927 :9E2BD7FD: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-05-30 22:00:04.023453 :9E2BDC04: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-05-30 22:00:04.023453*:9E2BDC06:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1748613603 eid=16781413 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-30 22:00:04.023453*:9E2BDC07:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-05-30 22:00:04.023453*:9E2BDC08:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-05-30 22:00:04.023453*:9E2BDC09:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-05-30 22:00:04.023453*:9E2BDC0A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-05-30 22:00:04.023453*:9E2BDC0B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-05-30 22:00:04.025623 :9E2BDC0C: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-05-30 22:00:04.025673 :9E2BDC0F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-30 22:00:04.034846 :9E2BDC15: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-05-30 22:00:04.035612 :9E2BDC1D: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-05-30 22:00:04.036323 :9E2BDC25: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-05-30 22:00:04.036535 :9E2BDC2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-30 22:00:04.037932 :9E2BDC2E: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-05-30 22:00:04.037972 :9E2BDC31: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-05-30 22:00:04.038043 :9E2BDC3E: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: 15252, J000)