Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250814220009\orcl_j005_20072_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:35472M/63366M, Ph+PgF:40786M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 20072, image: ORACLE.EXE (J005) *** 2025-08-14 22:00:09.310 *** SESSION ID:(249.1703) 2025-08-14 22:00:09.310 *** 2025-08-14 22:00:09.310 Process diagnostic dump for ORACLE.EXE (J005), OS id=20072, pid: 31, proc_ser: 65, sid: 249, sess_ser: 1703 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20072 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.255325 sec, exc=0.255325 sec, total=0.255325 sec wait times: max=0.500000 sec, heur=7.265322 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=13 seq_num=14 snap_id=1 wait times: snap=0.510136 sec, exc=0.510136 sec, total=0.510136 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 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=10 seq_num=11 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.505097 sec, exc=0.505097 sec, total=0.505097 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.502088 sec, exc=0.502088 sec, total=0.502088 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=6 seq_num=7 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499975 sec, exc=0.499975 sec, total=0.499975 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500015 sec, exc=0.500015 sec, total=0.500015 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time Sampled Session History of session 249 serial 1703 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:02 - 22:00:09] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-14 22:00:09.310 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 20072, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-10 06:00:02.070420 :BDBDB104:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=241 time=3486271376 2025-08-10 06:00:02.070420 :BDBDB105:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-10 06:00:02.070420 :BDBDB106:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-10 06:00:02.070421 :BDBDB107:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-10 06:00:02.070421 :BDBDB108:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-10 06:00:02.070422 :BDBDB109:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-10 06:00:02.071004 :BDBDB10A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-10 06:00:32.134868 :BDBDC21C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-10 23:59:17.826650 :BE113D84:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-10 23:59:17.817 2025-08-10 23:59:17.826652 :BE113D85:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18024 pso_num=31 pso_serial#=41 2025-08-10 23:59:17.826792 :BE113D86:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18024 (legacy spawn) 2025-08-10 23:59:17.834136 :BE113D87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-10 23:59:17.834311 :BE113D90:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-10 23:59:17.835188 :BE113D91:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-10 23:59:17.870403 :BE113DA0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-10 23:59:17.864 2025-08-10 23:59:17.870405 :BE113DA1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19060 pso_num=31 pso_serial#=42 2025-08-10 23:59:17.870546 :BE113DA2:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19060 (legacy spawn) 2025-08-10 23:59:17.891726 :BE113DA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-10 23:59:21.341139 :BE113E8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-10 23:59:21.341328 :BE113E91:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-10 23:59:21.342605 :BE113E96:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-10 23:59:21.606556 :BE113E99:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-10 23:59:21.601 2025-08-10 23:59:21.606558 :BE113E9A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20916 pso_num=31 pso_serial#=43 2025-08-10 23:59:21.606721 :BE113E9B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20916 (legacy spawn) 2025-08-10 23:59:21.622296 :BE113E9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-10 23:59:55.520131 :BE114B1D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-10 23:59:58.043716 :BE114BF7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-10 23:59:58.038 2025-08-10 23:59:58.043719 :BE114BF8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4644 pso_num=31 pso_serial#=44 2025-08-10 23:59:58.043853 :BE114BF9:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4644 (legacy spawn) 2025-08-10 23:59:58.057968 :BE114BFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 00:06:18.012764 :BE11D188:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 00:06:18.012933 :BE11D191:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-11 00:06:18.014003 :BE11D192:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-11 22:00:01.307307 :BE76F989:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-11 22:00:01.303 2025-08-11 22:00:01.307308 :BE76F98A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=316 pso_num=31 pso_serial#=45 2025-08-11 22:00:01.307425 :BE76F98B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=316 osp_idx=29 osp_ver=809 osp_pg=0 (spawn #479158) 2025-08-11 22:00:01.307427 :BE76F98C:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 22:00:01.307427 :BE76F98D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=242 time=3630270595 2025-08-11 22:00:01.307428 :BE76F98E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 22:00:01.307428 :BE76F98F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 22:00:01.307429 :BE76F990:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 22:00:01.307429 :BE76F991:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 22:00:01.307429 :BE76F992:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 22:00:01.307973 :BE76F9A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-11 22:00:31.376039 :BE770C82:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-11 23:59:18.283078 :BE80DFA6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-11 23:59:18.282 2025-08-11 23:59:18.283080 :BE80DFA7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20276 pso_num=31 pso_serial#=46 2025-08-11 23:59:18.283233 :BE80DFA8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20276 (legacy spawn) 2025-08-11 23:59:18.299445 :BE80DFA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 23:59:21.533146 :BE80E098:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 23:59:21.533330 :BE80E09D:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-11 23:59:21.534543 :BE80E0A2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-11 23:59:21.794759 :BE80E0E6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-11 23:59:21.786 2025-08-11 23:59:21.794762 :BE80E0E7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19980 pso_num=31 pso_serial#=47 2025-08-11 23:59:21.794939 :BE80E0E8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19980 (legacy spawn) 2025-08-11 23:59:21.811283 :BE80E10C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 23:59:54.924243 :BE80ED32:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-11 23:59:55.053030 :BE80ED35:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-11 23:59:55.046 2025-08-11 23:59:55.053032 :BE80ED36:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20448 pso_num=31 pso_serial#=48 2025-08-11 23:59:55.053211 :BE80ED37:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20448 (legacy spawn) 2025-08-11 23:59:55.067295 :BE80ED38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 23:59:58.316720 :BE80EE43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 23:59:58.316889 :BE80EE4C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-11 23:59:58.318122 :BE80EE4D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-11 23:59:58.580581 :BE80EE5B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-11 23:59:58.580 2025-08-11 23:59:58.580583 :BE80EE5C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20068 pso_num=31 pso_serial#=49 2025-08-11 23:59:58.580732 :BE80EE5D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20068 (legacy spawn) 2025-08-11 23:59:58.595588 :BE80EE5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 00:06:18.520780 :BE8176F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 00:06:18.520952 :BE817700:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 00:06:18.522174 :BE817701:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 22:00:02.069754 :BEE7981D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 22:00:02.066 2025-08-12 22:00:02.069755 :BEE7981E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11712 pso_num=31 pso_serial#=50 2025-08-12 22:00:02.069889 :BEE7982C:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11712 osp_idx=29 osp_ver=814 osp_pg=0 (spawn #482488) 2025-08-12 22:00:02.069890 :BEE7982D:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-12 22:00:02.069891 :BEE7982E:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=243 time=3716671361 2025-08-12 22:00:02.069891 :BEE7982F:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-12 22:00:02.069891 :BEE79830:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-12 22:00:02.069892 :BEE79831:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-12 22:00:02.069892 :BEE79832:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-12 22:00:02.069892 :BEE79833:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-12 22:00:02.070421 :BEE79838:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-12 22:00:32.146187 :BEE7AA17:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:06.811963 :BEF15A95:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:06.807 2025-08-12 23:59:06.811965 :BEF15A96:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20200 pso_num=31 pso_serial#=51 2025-08-12 23:59:06.812118 :BEF15A97:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20200 (legacy spawn) 2025-08-12 23:59:06.819366 :BEF15A98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:06.819557 :BEF15AA0:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:06.820460 :BEF15AA1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:06.830128 :BEF15AA4:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:06.823 2025-08-12 23:59:06.830130 :BEF15AA5:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21176 pso_num=31 pso_serial#=52 2025-08-12 23:59:06.830291 :BEF15AA6:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21176 (legacy spawn) 2025-08-12 23:59:06.837610 :BEF15AA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:06.837806 :BEF15AAF:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:06.838727 :BEF15AB1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:09.799819 :BEF15BAC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:09.786 2025-08-12 23:59:09.799821 :BEF15BAD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20384 pso_num=31 pso_serial#=53 2025-08-12 23:59:09.799958 :BEF15BAE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20384 (legacy spawn) 2025-08-12 23:59:09.814302 :BEF15BAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:43.099824 :BEF167B5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:43.252409 :BEF167B8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:43.238 2025-08-12 23:59:43.252411 :BEF167B9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19200 pso_num=31 pso_serial#=54 2025-08-12 23:59:43.252552 :BEF167BA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19200 (legacy spawn) 2025-08-12 23:59:43.259243 :BEF167BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:43.259409 :BEF167C4:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:43.260231 :BEF167C5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:43.269082 :BEF167C8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:43.269 2025-08-12 23:59:43.269083 :BEF167C9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20348 pso_num=31 pso_serial#=55 2025-08-12 23:59:43.269221 :BEF167CA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20348 (legacy spawn) 2025-08-12 23:59:43.275845 :BEF167CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:43.276014 :BEF167D3:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:43.276835 :BEF167D5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:45.009482 :BEF168D1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:45.004 2025-08-12 23:59:45.009485 :BEF168D2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19532 pso_num=31 pso_serial#=56 2025-08-12 23:59:45.009648 :BEF168D3:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19532 (legacy spawn) 2025-08-12 23:59:45.016480 :BEF168D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:45.016666 :BEF168D9:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:45.017542 :BEF168DE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:46.487999 :BEF16940:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:46.474 2025-08-12 23:59:46.488002 :BEF16941:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20488 pso_num=31 pso_serial#=57 2025-08-12 23:59:46.488145 :BEF16942:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20488 (legacy spawn) 2025-08-12 23:59:46.495059 :BEF16943:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:46.495235 :BEF16948:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:46.496097 :BEF1694D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:46.505522 :BEF16950:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:46.505 2025-08-12 23:59:46.505524 :BEF16951:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12684 pso_num=31 pso_serial#=58 2025-08-12 23:59:46.505674 :BEF16952:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12684 (legacy spawn) 2025-08-12 23:59:46.512839 :BEF16953:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-12 23:59:46.513006 :BEF1695C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-12 23:59:46.513929 :BEF1695D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-12 23:59:46.593291 :BEF16960:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-12 23:59:46.583 2025-08-12 23:59:46.593293 :BEF16961:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=21088 pso_num=31 pso_serial#=59 2025-08-12 23:59:46.593440 :BEF16962:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=21088 (legacy spawn) 2025-08-12 23:59:46.608007 :BEF1698E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 00:00:19.920127 :BEF1749A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-13 22:00:00.442798 :BF583DF5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-13 22:00:00.433 2025-08-13 22:00:00.442799 :BF583DF6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18620 pso_num=31 pso_serial#=60 2025-08-13 22:00:00.442944 :BF583DF7:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18620 osp_idx=29 osp_ver=824 osp_pg=0 (spawn #485813) 2025-08-13 22:00:00.442945 :BF583DF8:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-13 22:00:00.442946 :BF583DF9:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=244 time=3803069751 2025-08-13 22:00:00.442947 :BF583DFA:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-13 22:00:00.442947 :BF583DFB:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-13 22:00:00.442947 :BF583DFC:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-13 22:00:00.442948 :BF583DFD:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-13 22:00:00.442949 :BF583DFE:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-13 22:00:00.443581 :BF583DFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-13 22:00:30.536000 :BF584F04:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-13 23:59:09.674426 :BF61F8C3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-13 23:59:09.669 2025-08-13 23:59:09.674428 :BF61F8C4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15516 pso_num=31 pso_serial#=61 2025-08-13 23:59:09.674566 :BF61F8C5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=15516 (legacy spawn) 2025-08-13 23:59:09.688965 :BF61F8C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 23:59:09.690580 :BF61F8CF:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-13 23:59:13.028216 :BF61F9DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 23:59:13.028410 :BF61F9E5:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-13 23:59:13.029794 :BF61F9E6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-13 23:59:13.280290 :BF61F9F5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-13 23:59:13.277 2025-08-13 23:59:13.280292 :BF61F9F6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20684 pso_num=31 pso_serial#=62 2025-08-13 23:59:13.280424 :BF61F9F7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=20684 (legacy spawn) 2025-08-13 23:59:13.294562 :BF61FA21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 23:59:46.460262 :BF620616:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-13 23:59:46.527804 :BF620619:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-13 23:59:46.520 2025-08-13 23:59:46.527807 :BF62061A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=19264 pso_num=31 pso_serial#=63 2025-08-13 23:59:46.527957 :BF62061B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=19264 (legacy spawn) 2025-08-13 23:59:46.547727 :BF62061C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 23:59:49.878948 :BF62070C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-13 23:59:49.879127 :BF620711:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-13 23:59:49.880258 :BF620716:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-13 23:59:50.157116 :BF62077A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-13 23:59:50.153 2025-08-13 23:59:50.157118 :BF62077B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16664 pso_num=31 pso_serial#=64 2025-08-13 23:59:50.157277 :BF62077C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16664 (legacy spawn) 2025-08-13 23:59:50.173302 :BF62077D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 00:00:23.321538 :BF6213FE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-08-14 22:00:02.048940 :BFC81D60:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 08-14 22:00:02.041 2025-08-14 22:00:02.048941 :BFC81D61:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=20072 pso_num=31 pso_serial#=65 2025-08-14 22:00:02.049069 :BFC81D62:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=20072 osp_idx=29 osp_ver=829 osp_pg=0 (spawn #489147) 2025-08-14 22:00:02.049070 :BFC81D63:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 22:00:02.049070 :BFC81D64:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=245 time=3889471345 2025-08-14 22:00:02.049070 :BFC81D65:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 22:00:02.049071 :BFC81D66:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 22:00:02.049071 :BFC81D67:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 22:00:02.049072 :BFC81D68:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 22:00:02.049072 :BFC81D69:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 22:00:02.049571 :BFC81D6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 20072, J005)