Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250514220008\orcl_j005_11224_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:37412M/63366M, Ph+PgF:44697M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 11224, image: ORACLE.EXE (J005) *** 2025-05-14 22:00:08.699 *** SESSION ID:(249.669) 2025-05-14 22:00:08.699 *** 2025-05-14 22:00:08.699 Process diagnostic dump for ORACLE.EXE (J005), OS id=11224, pid: 31, proc_ser: 71, sid: 249, sess_ser: 669 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11224 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.499226 sec, exc=0.499226 sec, total=0.499226 sec wait times: max=0.500000 sec, heur=8.492383 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=15 seq_num=16 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.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 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=13 seq_num=14 snap_id=1 wait times: snap=0.501091 sec, exc=0.501091 sec, total=0.501091 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=12 seq_num=13 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000015 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 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=8 seq_num=9 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 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=7 seq_num=8 snap_id=1 wait times: snap=0.500302 sec, exc=0.500302 sec, total=0.500302 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 249 serial 669 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:00:00 - 22:00:08] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-14 22:00:08.699 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 11224, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-10 06:00:32.165627 :9518ADC5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-10 23:59:06.906040 :956CB244:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:06.898 2025-05-10 23:59:06.906042 :956CB245:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9288 pso_num=31 pso_serial#=52 2025-05-10 23:59:06.906174 :956CB246:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9288 (legacy spawn) 2025-05-10 23:59:06.912563 :956CB247: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-05-10 23:59:06.912737 :956CB24C: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-05-10 23:59:06.913543 :956CB251:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-10 23:59:08.422502 :956CB28B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:08.414 2025-05-10 23:59:08.422505 :956CB28C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9452 pso_num=31 pso_serial#=53 2025-05-10 23:59:08.422642 :956CB28D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9452 (legacy spawn) 2025-05-10 23:59:08.429289 :956CB28E: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-05-10 23:59:08.429465 :956CB293: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-05-10 23:59:08.430336 :956CB298:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-10 23:59:08.439258 :956CB29B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:08.429 2025-05-10 23:59:08.439260 :956CB29C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11376 pso_num=31 pso_serial#=54 2025-05-10 23:59:08.439395 :956CB29D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11376 (legacy spawn) 2025-05-10 23:59:08.446078 :956CB29E: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-05-10 23:59:08.446237 :956CB2A7: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-05-10 23:59:08.447059 :956CB2A8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-10 23:59:10.086960 :956CB377:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:10.086 2025-05-10 23:59:10.086962 :956CB378:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11412 pso_num=31 pso_serial#=55 2025-05-10 23:59:10.087096 :956CB379:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11412 (legacy spawn) 2025-05-10 23:59:10.101056 :956CB37A: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-05-10 23:59:13.250236 :956CB495: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-05-10 23:59:13.250267 :956CB498: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-05-10 23:59:13.250411 :956CB49B: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-05-10 23:59:13.251760 :956CB4AC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-10 23:59:13.509491 :956CB4BB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:13.510 2025-05-10 23:59:13.509493 :956CB4BC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13020 pso_num=31 pso_serial#=56 2025-05-10 23:59:13.509623 :956CB4BD:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13020 (legacy spawn) 2025-05-10 23:59:13.523580 :956CB4BE: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-05-10 23:59:46.537101 :956CC04A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-10 23:59:46.732890 :956CC04E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:46.722 2025-05-10 23:59:46.732892 :956CC04F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=3752 pso_num=31 pso_serial#=57 2025-05-10 23:59:46.733038 :956CC050:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=3752 (legacy spawn) 2025-05-10 23:59:46.746917 :956CC051: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-05-11 00:00:19.784942 :956CCD0F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-11 01:00:12.888939 :957160C1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 01:00:12.877 2025-05-11 01:00:12.888941 :957160C2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9992 pso_num=31 pso_serial#=58 2025-05-11 01:00:12.889072 :957160C3:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=9992 osp_idx=29 osp_ver=312 osp_pg=0 (spawn #169493) 2025-05-11 01:00:12.889073 :957160C4:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 01:00:12.889073 :957160C5:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=107 time=4195990046 2025-05-11 01:00:12.889074 :957160C6:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 01:00:12.889074 :957160C7:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 01:00:12.889075 :957160C8:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 01:00:12.889075 :957160C9:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 01:00:12.889075 :957160CA:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 01:00:12.889560 :957160CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 01:00:12.889602 :957160D1:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-11 01:00:12.896919 :957160DF: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-05-11 01:00:12.897968 :957160E7: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-05-11 01:00:12.898608 :957160EF: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-05-11 01:00:12.899082 :957160F7: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-05-11 01:00:12.899528 :957160FF: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-05-11 01:00:12.901993 :95716107: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-05-11 01:00:12.903177 :9571610F: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-05-11 01:00:12.952782 :9571612A: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-05-11 01:00:12.953672 :95716132: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-05-11 01:00:12.953919 :9571613A: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-05-11 01:00:12.954133 :95716142: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-05-11 01:00:12.955813 :9571614A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-11 06:00:02.076622 :95884789:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 06:00:02.069 2025-05-11 06:00:02.076623 :9588478A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12740 pso_num=31 pso_serial#=59 2025-05-11 06:00:02.076749 :9588478B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12740 osp_idx=29 osp_ver=313 osp_pg=0 (spawn #170203) 2025-05-11 06:00:02.076750 :9588478C:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:00:02.076750 :9588478D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=108 time=4213979234 2025-05-11 06:00:02.076751 :9588478E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:00:02.076751 :9588478F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:00:02.076752 :95884790:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:00:02.076752 :95884791:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:00:02.076753 :95884792:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:00:02.077247 :95884793: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-05-11 06:00:32.179385 :95885B57:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-11 08:26:02.278523 :9593E956:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 08:26:02.269 2025-05-11 08:26:02.278524 :9593E957:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10416 pso_num=31 pso_serial#=60 2025-05-11 08:26:02.278656 :9593E958:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=10416 osp_idx=29 osp_ver=314 osp_pg=0 (spawn #170555) 2025-05-11 08:26:02.278657 :9593E959:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 08:26:02.278658 :9593E95A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=109 time=4222739437 2025-05-11 08:26:02.278658 :9593E95B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 08:26:02.278658 :9593E95C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 08:26:02.278659 :9593E95D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 08:26:02.278659 :9593E95E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 08:26:02.278659 :9593E95F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 08:26:02.279155 :9593E960:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-11 08:26:02.279192 :9593E967:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-11 08:26:02.285463 :9593E974:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-11 23:59:09.977591 :95DC5EFC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 23:59:09.974 2025-05-11 23:59:09.977593 :95DC5EFD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13296 pso_num=31 pso_serial#=61 2025-05-11 23:59:09.977726 :95DC5EFE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13296 (legacy spawn) 2025-05-11 23:59:09.992253 :95DC5EFF: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-05-11 23:59:43.265089 :95DC6AC2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-11 23:59:53.223728 :95DC6E2B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 23:59:53.214 2025-05-11 23:59:53.223730 :95DC6E2C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10888 pso_num=31 pso_serial#=62 2025-05-11 23:59:53.223868 :95DC6E2D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10888 (legacy spawn) 2025-05-11 23:59:53.248808 :95DC6E2E: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-05-11 23:59:53.249003 :95DC6E33: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-05-11 23:59:53.249838 :95DC6E38:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-11 23:59:55.478343 :95DC6EE2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 23:59:55.464 2025-05-11 23:59:55.478345 :95DC6EE3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8180 pso_num=31 pso_serial#=63 2025-05-11 23:59:55.478495 :95DC6EE4:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8180 (legacy spawn) 2025-05-11 23:59:55.492259 :95DC6EE5: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-05-12 00:00:28.527687 :95DC7D8E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-12 22:00:02.065904 :96425017:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-12 22:00:02.064 2025-05-12 22:00:02.065905 :96425018:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12144 pso_num=31 pso_serial#=64 2025-05-12 22:00:02.066030 :96425019:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12144 osp_idx=29 osp_ver=318 osp_pg=0 (spawn #175825) 2025-05-12 22:00:02.066031 :9642501A:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-12 22:00:02.066031 :9642501B:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=110 time=63011922 2025-05-12 22:00:02.066032 :9642501C:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 22:00:02.066032 :9642501D:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 22:00:02.066033 :9642501E:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 22:00:02.066033 :9642501F:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 22:00:02.066033 :96425020:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 22:00:02.066550 :96425032: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-05-12 22:00:32.163888 :9642653A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-12 23:59:18.450310 :964B7C1E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-12 23:59:18.437 2025-05-12 23:59:18.450312 :964B7C1F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12592 pso_num=31 pso_serial#=65 2025-05-12 23:59:18.450448 :964B7C20:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12592 (legacy spawn) 2025-05-12 23:59:18.464396 :964B7C21: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-05-12 23:59:51.878088 :964B88C8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-12 23:59:59.750445 :964B8B81:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-12 23:59:59.747 2025-05-12 23:59:59.750447 :964B8B82:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11708 pso_num=31 pso_serial#=66 2025-05-12 23:59:59.750592 :964B8B83:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11708 (legacy spawn) 2025-05-12 23:59:59.764843 :964B8B84: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-05-13 00:06:19.723840 :964C1299: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-05-13 00:06:19.724002 :964C12A2: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-05-13 00:06:19.725288 :964C12A3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-13 22:00:02.165751 :96B13842:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-13 22:00:02.162 2025-05-13 22:00:02.165753 :96B13843:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12760 pso_num=31 pso_serial#=67 2025-05-13 22:00:02.165873 :96B13844:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12760 osp_idx=29 osp_ver=321 osp_pg=0 (spawn #179159) 2025-05-13 22:00:02.165874 :96B13845:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 22:00:02.165875 :96B13846:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=111 time=149412016 2025-05-13 22:00:02.165875 :96B13847:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 22:00:02.165876 :96B13848:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 22:00:02.165876 :96B13849:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 22:00:02.165876 :96B1384A:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 22:00:02.165877 :96B1384B:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 22:00:02.166392 :96B1385D: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-05-13 22:00:32.234768 :96B14CBF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-13 22:00:33.411936 :96B14D24:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-13 22:00:33.404 2025-05-13 22:00:33.411938 :96B14D25:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8696 pso_num=31 pso_serial#=68 2025-05-13 22:00:33.412072 :96B14D26:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=8696 osp_idx=29 osp_ver=322 osp_pg=0 (spawn #179162) 2025-05-13 22:00:33.412072 :96B14D27:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-13 22:00:33.412073 :96B14D28:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=112 time=149443282 2025-05-13 22:00:33.412073 :96B14D29:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-13 22:00:33.412074 :96B14D2A:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-13 22:00:33.412074 :96B14D2B:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-13 22:00:33.412074 :96B14D2C:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-13 22:00:33.412074 :96B14D2D:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-13 22:00:33.412589 :96B14D2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-13 22:00:33.412635 :96B14D34:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-13 22:00:33.415221 :96B14D42: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-05-13 22:00:33.419705 :96B14D4A: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-05-13 22:00:33.419715 :96B14D4B:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0007.011.0000c1f8 2025-05-13 22:00:33.420233 :96B14D53:db_trace:ktu.c@4871:ktuaex1r(): [10442:31:249] Extension usn 7, ext 1, nex 2 2025-05-13 22:00:33.420239 :96B14D54:db_trace:ktusm.c@2117:ktusmasp(): [10445:31:249] des1:tsn:1 rdba:0x0081af74 where: kdiwh187: kdisprobe 2025-05-13 22:00:33.421421 :96B14D55: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-05-13 22:00:33.422542 :96B14D5D: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-05-13 22:00:33.423100 :96B14D65: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-05-13 22:00:33.423580 :96B14D6D: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-05-13 22:00:33.424015 :96B14D75: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-05-13 22:00:33.426440 :96B14D7D: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-05-13 22:00:33.427542 :96B14D85: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-05-13 22:00:33.476968 :96B14D9B: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-05-13 22:00:33.477842 :96B14DA3: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-05-13 22:00:33.478074 :96B14DAB: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-05-13 22:00:33.478295 :96B14DB3: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-05-13 22:00:33.480099 :96B14DBB:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-13 23:59:09.852514 :96BA65BA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-13 23:59:09.837 2025-05-13 23:59:09.852516 :96BA65BB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6600 pso_num=31 pso_serial#=69 2025-05-13 23:59:09.852653 :96BA65BC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=6600 (legacy spawn) 2025-05-13 23:59:09.866552 :96BA65BD: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-05-13 23:59:43.080340 :96BA7192:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-13 23:59:46.642424 :96BA72A8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-13 23:59:46.641 2025-05-13 23:59:46.642426 :96BA72A9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2884 pso_num=31 pso_serial#=70 2025-05-13 23:59:46.642562 :96BA72AA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=2884 (legacy spawn) 2025-05-13 23:59:46.656083 :96BA72AB: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-05-14 00:00:19.862846 :96BA7D92:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-14 22:00:00.221047 :97200DFF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-14 22:00:00.213 2025-05-14 22:00:00.221048 :97200E00:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11224 pso_num=31 pso_serial#=71 2025-05-14 22:00:00.221204 :97200E08:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11224 osp_idx=29 osp_ver=325 osp_pg=0 (spawn #182493) 2025-05-14 22:00:00.221204 :97200E09:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-14 22:00:00.221205 :97200E0A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=113 time=235810094 2025-05-14 22:00:00.221206 :97200E0B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-14 22:00:00.221206 :97200E0C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-14 22:00:00.221207 :97200E0D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-14 22:00:00.221208 :97200E0E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-14 22:00:00.221208 :97200E0F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-14 22:00:00.221826 :97200E10: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: 11224, J005)