Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426140626\orcl_j001_9948_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:37617M/63366M, Ph+PgF:45343M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9948, image: ORACLE.EXE (J001) *** 2025-04-26 14:06:26.778 *** SESSION ID:(193.13127) 2025-04-26 14:06:26.778 *** 2025-04-26 14:06:26.778 Process diagnostic dump for ORACLE.EXE (J001), OS id=9948, pid: 24, proc_ser: 27, sid: 193, sess_ser: 13127 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9948 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.189457 sec, exc=0.189457 sec, total=0.189457 sec wait times: max=0.500000 sec, heur=6.696070 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500117 sec, exc=0.500117 sec, total=0.500117 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.508233 sec, exc=0.508233 sec, total=0.508233 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 193 serial 13127 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 14:06:20 - 14:06:26] idle wait at each sample [session created at: 14:06:20] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-26 14:06:26.778 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9948, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-26 13:57:12.862701 :8F089705:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 13:57:12.852 2025-04-26 13:57:12.862703 :8F089706:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2936 pso_num=24 pso_serial#=17 2025-04-26 13:57:12.862832 :8F089707:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=2936 osp_idx=22 osp_ver=175966 osp_pg=0 (spawn #120460) 2025-04-26 13:57:12.862832 :8F089708:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 13:57:12.862833 :8F089709:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=188 time=2946634656 2025-04-26 13:57:12.862833 :8F08970A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 13:57:12.862834 :8F08970B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 13:57:12.862834 :8F08970C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 13:57:12.862834 :8F08970D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 13:57:12.862835 :8F08970E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 13:57:12.863328 :8F08970F: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-04-26 13:57:42.914624 :8F08A22E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 13:58:12.927496 :8F08AC52:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 13:58:12.916 2025-04-26 13:58:12.927498 :8F08AC53:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10904 pso_num=24 pso_serial#=18 2025-04-26 13:58:12.927628 :8F08AC54:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10904 osp_idx=22 osp_ver=175967 osp_pg=0 (spawn #120462) 2025-04-26 13:58:12.927628 :8F08AC55:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 13:58:12.927629 :8F08AC56:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=189 time=2946694718 2025-04-26 13:58:12.927629 :8F08AC57:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 13:58:12.927630 :8F08AC58:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 13:58:12.927630 :8F08AC59:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 13:58:12.927630 :8F08AC5A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 13:58:12.927630 :8F08AC5B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 13:58:12.928120 :8F08AC5C: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-04-26 13:58:43.021845 :8F08B646:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 13:59:08.013448 :8F08BE4C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 13:59:08.003 2025-04-26 13:59:08.013450 :8F08BE4D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10260 pso_num=24 pso_serial#=19 2025-04-26 13:59:08.013593 :8F08BE4E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10260 osp_idx=22 osp_ver=175968 osp_pg=0 (spawn #120464) 2025-04-26 13:59:08.013594 :8F08BE4F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 13:59:08.013594 :8F08BE50:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=190 time=2946749796 2025-04-26 13:59:08.013595 :8F08BE51:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 13:59:08.013595 :8F08BE52:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 13:59:08.013596 :8F08BE53:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 13:59:08.013596 :8F08BE54:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 13:59:08.013597 :8F08BE55:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 13:59:08.014083 :8F08BE56: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-04-26 13:59:13.006454 :8F08C024: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-04-26 13:59:43.095915 :8F08CA85:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:00:13.091311 :8F08D466:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:00:13.080 2025-04-26 14:00:13.091313 :8F08D467:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10484 pso_num=24 pso_serial#=20 2025-04-26 14:00:13.091451 :8F08D468:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10484 osp_idx=22 osp_ver=175969 osp_pg=0 (spawn #120466) 2025-04-26 14:00:13.091451 :8F08D469:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:00:13.091452 :8F08D46A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=191 time=2946814875 2025-04-26 14:00:13.091452 :8F08D46B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:00:13.091453 :8F08D46C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:00:13.091453 :8F08D46D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:00:13.091453 :8F08D46E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:00:13.091454 :8F08D46F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:00:13.091947 :8F08D470: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-04-26 14:00:43.186681 :8F08E078:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:01:13.151820 :8F08EA72:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:01:13.141 2025-04-26 14:01:13.151822 :8F08EA73:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1872 pso_num=24 pso_serial#=21 2025-04-26 14:01:13.151954 :8F08EA74:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=1872 osp_idx=22 osp_ver=175970 osp_pg=0 (spawn #120471) 2025-04-26 14:01:13.151954 :8F08EA75:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:01:13.151955 :8F08EA76:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=192 time=2946874937 2025-04-26 14:01:13.151955 :8F08EA77:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:01:13.151956 :8F08EA78:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:01:13.151956 :8F08EA79:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:01:13.151956 :8F08EA7A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:01:13.151957 :8F08EA7B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:01:13.152496 :8F08EA7C: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-04-26 14:01:43.245389 :8F08F480:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:02:13.238256 :8F08FE38:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:02:13.227 2025-04-26 14:02:13.238258 :8F08FE39:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9992 pso_num=24 pso_serial#=22 2025-04-26 14:02:13.238386 :8F08FE3A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9992 osp_idx=22 osp_ver=175971 osp_pg=0 (spawn #120474) 2025-04-26 14:02:13.238387 :8F08FE3B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:02:13.238387 :8F08FE3C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=193 time=2946935031 2025-04-26 14:02:13.238387 :8F08FE3D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:02:13.238388 :8F08FE3E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:02:13.238388 :8F08FE3F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:02:13.238389 :8F08FE40:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:02:13.238389 :8F08FE41:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:02:13.238867 :8F08FE42: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-04-26 14:02:43.347510 :8F09089E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:03:13.334448 :8F0912CE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:03:13.324 2025-04-26 14:03:13.334450 :8F0912CF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10880 pso_num=24 pso_serial#=23 2025-04-26 14:03:13.334592 :8F0912D0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10880 osp_idx=22 osp_ver=175972 osp_pg=0 (spawn #120476) 2025-04-26 14:03:13.334593 :8F0912D1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:03:13.334593 :8F0912D2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=194 time=2946995125 2025-04-26 14:03:13.334593 :8F0912D3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:03:13.334594 :8F0912D4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:03:13.334595 :8F0912D5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:03:13.334595 :8F0912D6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:03:13.334595 :8F0912D7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:03:13.335093 :8F0912D8: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-04-26 14:03:43.396020 :8F091CDD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:04:13.391134 :8F0926C5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:04:13.380 2025-04-26 14:04:13.391136 :8F0926C6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10912 pso_num=24 pso_serial#=24 2025-04-26 14:04:13.391266 :8F0926C7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10912 osp_idx=22 osp_ver=175973 osp_pg=0 (spawn #120478) 2025-04-26 14:04:13.391267 :8F0926C8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:04:13.391267 :8F0926C9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=195 time=2947055187 2025-04-26 14:04:13.391267 :8F0926CA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:04:13.391268 :8F0926CB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:04:13.391268 :8F0926CC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:04:13.391269 :8F0926CD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:04:13.391269 :8F0926CE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:04:13.391744 :8F0926CF: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-04-26 14:04:43.497847 :8F093192:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:04:43.589033 :8F0931B5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:04:43.575 2025-04-26 14:04:43.589035 :8F0931B6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9504 pso_num=24 pso_serial#=25 2025-04-26 14:04:43.589171 :8F0931B7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9504 (legacy spawn) 2025-04-26 14:04:43.595601 :8F0931B8: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-04-26 14:04:43.595786 :8F0931BD: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-04-26 14:04:43.596566 :8F0931C2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:05:13.493030 :8F093B65:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:05:13.482 2025-04-26 14:05:13.493031 :8F093B66:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9412 pso_num=24 pso_serial#=26 2025-04-26 14:05:13.493166 :8F093B67:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9412 osp_idx=22 osp_ver=175975 osp_pg=0 (spawn #120480) 2025-04-26 14:05:13.493167 :8F093B68:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:05:13.493167 :8F093B69:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=196 time=2947115281 2025-04-26 14:05:13.493168 :8F093B6A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:05:13.493168 :8F093B6B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:05:13.493168 :8F093B6C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:05:13.493168 :8F093B6D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:05:13.493169 :8F093B6E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:05:13.493649 :8F093B6F: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-04-26 14:05:43.573587 :8F094BEF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 14:06:13.590890 :8F0955C9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 14:06:13.580 2025-04-26 14:06:13.590892 :8F0955CA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9948 pso_num=24 pso_serial#=27 2025-04-26 14:06:13.591027 :8F0955CB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9948 osp_idx=22 osp_ver=175976 osp_pg=0 (spawn #120482) 2025-04-26 14:06:13.591027 :8F0955CC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 14:06:13.591028 :8F0955CD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=197 time=2947175375 2025-04-26 14:06:13.591028 :8F0955CE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 14:06:13.591028 :8F0955CF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 14:06:13.591029 :8F0955D0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 14:06:13.591029 :8F0955D1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 14:06:13.591030 :8F0955D2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 14:06:13.591569 :8F0955D3: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-04-26 14:06:18.334152 :8F095905: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-04-26 14:06:18.443161 :8F095C36: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-04-26 14:06:18.443161*:8F095C44:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1745647578 eid=16779305 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-26 14:06:18.443161*:8F095C45:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-04-26 14:06:18.443161*:8F095C46:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-04-26 14:06:18.443161*:8F095C47:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-04-26 14:06:18.443161*:8F095C48:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-04-26 14:06:18.443161*:8F095C49:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-04-26 14:06:18.448350 :8F095C54: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-04-26 14:06:18.448396 :8F095C57:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-04-26 14:06:18.452235 :8F095C67: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-04-26 14:06:18.558374 :8F095C6F: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-04-26 14:06:18.687688 :8F095C84: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-04-26 14:06:18.817230 :8F095C8C: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-04-26 14:06:19.945978 :8F095CF1: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-04-26 14:06:19.947039 :8F095CF9: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-04-26 14:06:19.948776 :8F095D01: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-04-26 14:06:19.951326 :8F095D09: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-04-26 14:06:19.954596 :8F095D11: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-04-26 14:06:19.969947 :8F095D19: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-04-26 14:06:19.970546 :8F095D21: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-04-26 14:06:19.971049 :8F095D29: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-04-26 14:06:19.971658 :8F095D31: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-04-26 14:06:19.974244 :8F095D39: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-04-26 14:06:19.975178 :8F095D41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:19.976019 :8F095D42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:19.976809 :8F095D43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:19.977243 :8F095D44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:19.977249 :8F095D45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:19.977809 :8F095D46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:19.978311 :8F095D47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:19.978591 :8F095D48:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:19.979719 :8F095D49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:20.010240 :8F095D4A: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-04-26 14:06:20.010292 :8F095D4D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:20.011202 :8F095D53: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-04-26 14:06:20.012065 :8F095D5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:20.012479 :8F095D5C: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-04-26 14:06:20.032369 :8F095D64: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-04-26 14:06:20.032422 :8F095D67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:20.033353 :8F095D6D: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-04-26 14:06:20.034226 :8F095D80:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:20.034678 :8F095D81: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-04-26 14:06:20.055654 :8F095D93: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-04-26 14:06:20.055708 :8F095D96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:20.056637 :8F095D9C: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-04-26 14:06:20.057488 :8F095DA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 14:06:20.057935 :8F095DA5: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-04-26 14:06:20.074591 :8F095DE4: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-04-26 14:06:20.074642 :8F095DE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 14:06:20.075492 :8F095DED: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-04-26 14:06:20.075944 :8F095DF5: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-04-26 14:06:20.076346 :8F095DFD: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-04-26 14:06:20.076525 :8F095E05: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-04-26 14:06:20.076670 :8F095E0D: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-04-26 14:06:20.076811 :8F095E15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-04-26 14:06:20.080840 :8F095E16: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-04-26 14:06:20.080922 :8F095E19: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-04-26 14:06:20.080995 :8F095E26: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: 9948, J001)