Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250606222131\orcl_w000_14052_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:36856M/63366M, Ph+PgF:43528M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14052, image: ORACLE.EXE (W000) *** 2025-06-06 22:21:31.991 *** SESSION ID:(201.63137) 2025-06-06 22:21:31.991 *** 2025-06-06 22:21:31.991 Process diagnostic dump for ORACLE.EXE (W000), OS id=14052, pid: 25, proc_ser: 105, sid: 201, sess_ser: 63137 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=2.814430 sec, exc=2.814430 sec, total=2.814430 sec wait times: max=5.000000 sec, heur=32.847491 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.000019 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.004656 sec, exc=5.004656 sec, total=5.004656 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=5.014756 sec, exc=5.014756 sec, total=5.014756 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.015244 sec, exc=5.015244 sec, total=5.015244 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=5.003382 sec, exc=5.003382 sec, total=5.003382 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=4.999979 sec, exc=4.999979 sec, total=4.999979 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=4.994905 sec, exc=4.994905 sec, total=4.994905 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.003994 sec of elapsed time 6: waited for 'class slave wait' slave id=0x8871f50, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000040 sec, exc=0.000040 sec, total=0.000040 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 63137 --------------------------------------------------- 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 --------------------------------------------------- [33 samples, 22:20:59 - 22:21:31] idle wait at each sample [session created at: 22:20:59] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-06 22:21:31.991 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14052, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-06 19:50:41.620108 :A13257F0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 19:50:41.620108 :A13257F1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 19:50:41.620613 :A13257F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 19:50:41.620656 :A13257F9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 20:00:42.450353 :A1331D7E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 20:00:42.673658 :A1331DB4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 20:00:42.669 2025-06-06 20:00:42.673660 :A1331DB5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14604 pso_num=25 pso_serial#=92 2025-06-06 20:00:42.673799 :A1331DB6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14604 osp_idx=23 osp_ver=146064 osp_pg=0 (spawn #258847) 2025-06-06 20:00:42.673799 :A1331DB7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 20:00:42.673800 :A1331DB8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=49 time=2215801329 2025-06-06 20:00:42.673800 :A1331DB9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 20:00:42.673801 :A1331DBA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 20:00:42.673801 :A1331DBB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 20:00:42.673801 :A1331DBC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 20:00:42.673802 :A1331DBD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 20:00:42.674289 :A1331DBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 20:00:42.674337 :A1331DC5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 20:10:43.389180 :A133EA57:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 20:10:43.815616 :A133EAC3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 20:10:43.810 2025-06-06 20:10:43.815619 :A133EAC4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16056 pso_num=25 pso_serial#=93 2025-06-06 20:10:43.815757 :A133EAC5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16056 osp_idx=23 osp_ver=146065 osp_pg=0 (spawn #258873) 2025-06-06 20:10:43.815758 :A133EAC6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 20:10:43.815758 :A133EAC7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=50 time=2216402469 2025-06-06 20:10:43.815759 :A133EAC8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 20:10:43.815759 :A133EAC9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 20:10:43.815759 :A133EACA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 20:10:43.815760 :A133EACB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 20:10:43.815760 :A133EACC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 20:10:43.816273 :A133EACD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 20:10:43.816314 :A133EAD6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 20:20:44.604691 :A134B239:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 20:20:44.937259 :A134B295:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 20:20:44.932 2025-06-06 20:20:44.937261 :A134B296:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15328 pso_num=25 pso_serial#=94 2025-06-06 20:20:44.937400 :A134B297:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15328 osp_idx=23 osp_ver=146066 osp_pg=0 (spawn #258895) 2025-06-06 20:20:44.937401 :A134B298:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 20:20:44.937401 :A134B299:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=51 time=2217003579 2025-06-06 20:20:44.937401 :A134B29A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 20:20:44.937402 :A134B29B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 20:20:44.937402 :A134B29C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 20:20:44.937402 :A134B29D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 20:20:44.937403 :A134B29E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 20:20:44.937888 :A134B29F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 20:20:44.937933 :A134B2A9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 20:30:45.811333 :A13577EA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 20:30:46.097438 :A1357823:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 20:30:46.092 2025-06-06 20:30:46.097440 :A1357824:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16136 pso_num=25 pso_serial#=95 2025-06-06 20:30:46.097585 :A1357825:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16136 osp_idx=23 osp_ver=146067 osp_pg=0 (spawn #258918) 2025-06-06 20:30:46.097586 :A1357826:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 20:30:46.097586 :A1357827:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=52 time=2217604750 2025-06-06 20:30:46.097587 :A1357828:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 20:30:46.097587 :A1357829:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 20:30:46.097587 :A135782A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 20:30:46.097587 :A135782B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 20:30:46.097588 :A135782C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 20:30:46.098080 :A135782D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 20:30:46.098131 :A1357835:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 20:40:46.982857 :A1363DAD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 20:40:47.237417 :A1363E08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 20:40:47.232 2025-06-06 20:40:47.237421 :A1363E09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15312 pso_num=25 pso_serial#=96 2025-06-06 20:40:47.237559 :A1363E0A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15312 osp_idx=23 osp_ver=146068 osp_pg=0 (spawn #258941) 2025-06-06 20:40:47.237560 :A1363E0B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 20:40:47.237560 :A1363E0C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=53 time=2218205891 2025-06-06 20:40:47.237561 :A1363E0D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 20:40:47.237561 :A1363E0E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 20:40:47.237561 :A1363E0F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 20:40:47.237562 :A1363E10:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 20:40:47.237562 :A1363E11:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 20:40:47.238061 :A1363E12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 20:40:47.238112 :A1363E1A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 20:50:48.144214 :A1370252:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 20:50:48.398952 :A13702AF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 20:50:48.394 2025-06-06 20:50:48.398954 :A13702B0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9904 pso_num=25 pso_serial#=97 2025-06-06 20:50:48.399096 :A13702B1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9904 osp_idx=23 osp_ver=146069 osp_pg=0 (spawn #258963) 2025-06-06 20:50:48.399097 :A13702B2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 20:50:48.399097 :A13702B3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=54 time=2218807047 2025-06-06 20:50:48.399098 :A13702B4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 20:50:48.399098 :A13702B5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 20:50:48.399098 :A13702B6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 20:50:48.399099 :A13702B7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 20:50:48.399099 :A13702B8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 20:50:48.399636 :A13702B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 20:50:48.399691 :A13702C0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 21:00:49.195898 :A137CC69:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 21:00:49.523346 :A137CCC8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 21:00:49.519 2025-06-06 21:00:49.523348 :A137CCC9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7776 pso_num=25 pso_serial#=98 2025-06-06 21:00:49.523496 :A137CCCA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7776 osp_idx=23 osp_ver=146070 osp_pg=0 (spawn #258988) 2025-06-06 21:00:49.523497 :A137CCCB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 21:00:49.523497 :A137CCCC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=55 time=2219408172 2025-06-06 21:00:49.523498 :A137CCCD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 21:00:49.523498 :A137CCCE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 21:00:49.523499 :A137CCCF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 21:00:49.523499 :A137CCD0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 21:00:49.523500 :A137CCD1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 21:00:49.524017 :A137CCD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 21:00:49.524063 :A137CCD9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 21:10:50.415990 :A1389825:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 21:10:50.686202 :A1389888:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 21:10:50.686 2025-06-06 21:10:50.686204 :A1389889:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15536 pso_num=25 pso_serial#=99 2025-06-06 21:10:50.686343 :A138988A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15536 osp_idx=23 osp_ver=146071 osp_pg=0 (spawn #259012) 2025-06-06 21:10:50.686344 :A138988B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 21:10:50.686344 :A138988C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=56 time=2220009329 2025-06-06 21:10:50.686345 :A138988D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 21:10:50.686345 :A138988E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 21:10:50.686346 :A138988F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 21:10:50.686346 :A1389890:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 21:10:50.686346 :A1389891:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 21:10:50.686842 :A1389892:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 21:10:50.686888 :A1389899:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 21:20:51.572194 :A1395FC4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 21:20:51.857843 :A1396021:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 21:20:51.853 2025-06-06 21:20:51.857845 :A1396022:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15680 pso_num=25 pso_serial#=100 2025-06-06 21:20:51.857989 :A1396023:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15680 osp_idx=23 osp_ver=146072 osp_pg=0 (spawn #259034) 2025-06-06 21:20:51.857990 :A1396024:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 21:20:51.857991 :A1396025:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=57 time=2220610500 2025-06-06 21:20:51.857991 :A1396026:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 21:20:51.857992 :A1396027:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 21:20:51.857992 :A1396028:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 21:20:51.857992 :A1396029:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 21:20:51.857993 :A139602A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 21:20:51.858496 :A139602B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 21:20:51.858542 :A1396034:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 21:30:52.718736 :A13A2744:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 21:30:53.019741 :A13A27A5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 21:30:53.015 2025-06-06 21:30:53.019744 :A13A27A6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15668 pso_num=25 pso_serial#=101 2025-06-06 21:30:53.019876 :A13A27A7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15668 osp_idx=23 osp_ver=146073 osp_pg=0 (spawn #259057) 2025-06-06 21:30:53.019877 :A13A27A8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 21:30:53.019877 :A13A27A9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=58 time=2221211672 2025-06-06 21:30:53.019877 :A13A27AA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 21:30:53.019878 :A13A27AB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 21:30:53.019878 :A13A27AC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 21:30:53.019878 :A13A27AD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 21:30:53.019879 :A13A27AE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 21:30:53.020434 :A13A27AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 21:30:53.020482 :A13A27B6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 21:40:19.430286 :A13AE2DF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-06 21:40:54.158122 :A13AEFDB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-06 21:50:55.093451 :A13BB6F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 21:50:55.399377 :A13BB757:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 21:50:55.394 2025-06-06 21:50:55.399379 :A13BB758:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12000 pso_num=25 pso_serial#=102 2025-06-06 21:50:55.399515 :A13BB759:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12000 osp_idx=23 osp_ver=146074 osp_pg=0 (spawn #259101) 2025-06-06 21:50:55.399516 :A13BB75A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 21:50:55.399517 :A13BB75B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=59 time=2222414047 2025-06-06 21:50:55.399517 :A13BB75C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 21:50:55.399517 :A13BB75D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 21:50:55.399518 :A13BB75E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 21:50:55.399518 :A13BB75F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 21:50:55.399518 :A13BB760:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 21:50:55.399998 :A13BB761:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 21:50:55.400042 :A13BB769:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 22:00:56.237315 :A13C8ABD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 22:00:56.633137 :A13C8B41:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 22:00:56.628 2025-06-06 22:00:56.633139 :A13C8B42:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13284 pso_num=25 pso_serial#=103 2025-06-06 22:00:56.633275 :A13C8B43:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13284 osp_idx=23 osp_ver=146075 osp_pg=0 (spawn #259132) 2025-06-06 22:00:56.633276 :A13C8B44:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:00:56.633276 :A13C8B45:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=60 time=2223015282 2025-06-06 22:00:56.633277 :A13C8B46:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:00:56.633277 :A13C8B47:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:00:56.633277 :A13C8B48:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:00:56.633277 :A13C8B49:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:00:56.633278 :A13C8B4A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:00:56.633770 :A13C8B4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:00:56.633817 :A13C8B53:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 22:10:57.453207 :A13D5EFE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 22:10:57.848986 :A13D5F7C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 22:10:57.844 2025-06-06 22:10:57.848987 :A13D5F7D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16104 pso_num=25 pso_serial#=104 2025-06-06 22:10:57.849126 :A13D5F7E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16104 osp_idx=23 osp_ver=146076 osp_pg=0 (spawn #259156) 2025-06-06 22:10:57.849127 :A13D5F7F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:10:57.849127 :A13D5F80:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=61 time=2223616500 2025-06-06 22:10:57.849128 :A13D5F81:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:10:57.849128 :A13D5F82:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:10:57.849128 :A13D5F83:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:10:57.849129 :A13D5F84:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:10:57.849129 :A13D5F85:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:10:57.849627 :A13D5F86:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:10:57.849673 :A13D5F8D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-06 22:20:58.751601 :A13E268D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 22:20:59.146761 :A13E26FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 22:20:59.142 2025-06-06 22:20:59.146764 :A13E26FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14052 pso_num=25 pso_serial#=105 2025-06-06 22:20:59.146902 :A13E26FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14052 osp_idx=23 osp_ver=146077 osp_pg=0 (spawn #259178) 2025-06-06 22:20:59.146903 :A13E26FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-06 22:20:59.146904 :A13E26FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=62 time=2224217797 2025-06-06 22:20:59.146904 :A13E26FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 22:20:59.146904 :A13E2700:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 22:20:59.146905 :A13E2701:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 22:20:59.146905 :A13E2702:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 22:20:59.146905 :A13E2703:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 22:20:59.147390 :A13E2704:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-06 22:20:59.147436 :A13E270B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 14052, W000)