Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250606220007\orcl_w000_12000_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:36814M/63366M, Ph+PgF:43432M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 12000, image: ORACLE.EXE (W000) *** 2025-06-06 22:00:07.818 *** SESSION ID:(201.63131) 2025-06-06 22:00:07.818 *** 2025-06-06 22:00:07.818 Process diagnostic dump for ORACLE.EXE (W000), OS id=12000, pid: 25, proc_ser: 102, sid: 201, sess_ser: 63131 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=111 seq_num=112 snap_id=1 wait times: snap=1.661521 sec, exc=1.661521 sec, total=1.661521 sec wait times: max=5.000000 sec, heur=9 min 12 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.000026 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=110 seq_num=111 snap_id=1 wait times: snap=5.009916 sec, exc=5.009916 sec, total=5.009916 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=109 seq_num=110 snap_id=1 wait times: snap=5.013561 sec, exc=5.013561 sec, total=5.013561 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=108 seq_num=109 snap_id=1 wait times: snap=5.009243 sec, exc=5.009243 sec, total=5.009243 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=107 seq_num=108 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=5.004650 sec, exc=5.004650 sec, total=5.004650 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=105 seq_num=106 snap_id=1 wait times: snap=5.004668 sec, exc=5.004668 sec, total=5.004668 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=104 seq_num=105 snap_id=1 wait times: snap=5.002633 sec, exc=5.002633 sec, total=5.002633 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=103 seq_num=104 snap_id=1 wait times: snap=5.013802 sec, exc=5.013802 sec, total=5.013802 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=102 seq_num=103 snap_id=1 wait times: snap=5.014744 sec, exc=5.014744 sec, total=5.014744 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=101 seq_num=102 snap_id=1 wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 201 serial 63131 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 21:58:07 - 22:00:07] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-06 22:00:07.818 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 12000, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-06 19:10:36.650718 :A12F4268: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 19:20:37.393338 :A13007EB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 19:20:37.898101 :A130083A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 19:20:37.893 2025-06-06 19:20:37.898103 :A130083B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12992 pso_num=25 pso_serial#=89 2025-06-06 19:20:37.898248 :A130083C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12992 osp_idx=23 osp_ver=146061 osp_pg=0 (spawn #258757) 2025-06-06 19:20:37.898249 :A130083D: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 19:20:37.898249 :A130083E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=46 time=2213396547 2025-06-06 19:20:37.898250 :A130083F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 19:20:37.898251 :A1300840:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 19:20:37.898251 :A1300841:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 19:20:37.898251 :A1300842:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 19:20:37.898252 :A1300843:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 19:20:37.898782 :A1300844: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:20:37.898826 :A130084B: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 19:30:38.674083 :A130CDE3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 19:30:39.084980 :A130CE30:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 19:30:39.080 2025-06-06 19:30:39.084982 :A130CE31:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16132 pso_num=25 pso_serial#=90 2025-06-06 19:30:39.085115 :A130CE32:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16132 osp_idx=23 osp_ver=146062 osp_pg=0 (spawn #258780) 2025-06-06 19:30:39.085115 :A130CE33: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 19:30:39.085116 :A130CE34:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=47 time=2213997735 2025-06-06 19:30:39.085116 :A130CE35:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 19:30:39.085117 :A130CE36:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 19:30:39.085117 :A130CE37:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-06 19:30:39.085117 :A130CE38:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-06 19:30:39.085118 :A130CE39:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-06 19:30:39.085629 :A130CE3A: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:30:39.085672 :A130CE43: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 19:40:18.775378 :A1318A6E: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 19:40:40.373372 :A131938F: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 19:50:41.271766 :A1325786:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-06 19:50:41.619960 :A13257E8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-06 19:50:41.615 2025-06-06 19:50:41.619962 :A13257E9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12492 pso_num=25 pso_serial#=91 2025-06-06 19:50:41.620106 :A13257EA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12492 osp_idx=23 osp_ver=146063 osp_pg=0 (spawn #258824) 2025-06-06 19:50:41.620106 :A13257EB: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 19:50:41.620107 :A13257EC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=48 time=2215200266 2025-06-06 19:50:41.620107 :A13257ED:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-06 19:50:41.620107 :A13257EE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-06 19:50:41.620108 :A13257EF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 12000, W000)