Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250908220008\orcl_w000_24120_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:34900M/63366M, Ph+PgF:39635M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 24120, image: ORACLE.EXE (W000) *** 2025-09-08 22:00:08.370 *** SESSION ID:(177.15951) 2025-09-08 22:00:08.370 *** 2025-09-08 22:00:08.370 Process diagnostic dump for ORACLE.EXE (W000), OS id=24120, pid: 22, proc_ser: 49, sid: 177, sess_ser: 15951 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=2.169142 sec, exc=2.169142 sec, total=2.169142 sec wait times: max=5.000000 sec, heur=7 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.000039 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.012585 sec, exc=5.012585 sec, total=5.012585 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 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.000036 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.010128 sec, exc=5.010128 sec, total=5.010128 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=83 seq_num=84 snap_id=1 wait times: snap=5.009212 sec, exc=5.009212 sec, total=5.009212 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.004618 sec, exc=5.004618 sec, total=5.004618 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=5.008239 sec, exc=5.008239 sec, total=5.008239 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=80 seq_num=81 snap_id=1 wait times: snap=5.000258 sec, exc=5.000258 sec, total=5.000258 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=5.002509 sec, exc=5.002509 sec, total=5.002509 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=5.010138 sec, exc=5.010138 sec, total=5.010138 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.014722 sec, exc=5.014722 sec, total=5.014722 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time Sampled Session History of session 177 serial 15951 --------------------------------------------------- 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:08 - 22:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-08 22:00:08.370 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 24120, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-08 19:22:37.861303 :CAC624EB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 19:22:37.861304 :CAC624EC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 19:22:37.861835 :CAC624ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 19:22:37.861887 :CAC624F3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 19:32:38.719766 :CAC6E79E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 19:32:38.984741 :CAC6E7DE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 19:32:38.980 2025-09-08 19:32:38.984744 :CAC6E7DF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22800 pso_num=22 pso_serial#=36 2025-09-08 19:32:38.984889 :CAC6E7E0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22800 osp_idx=0 osp_ver=586863 osp_pg=0 (spawn #572152) 2025-09-08 19:32:38.984890 :CAC6E7E1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 19:32:38.984891 :CAC6E7E2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=29 time=1745610283 2025-09-08 19:32:38.984891 :CAC6E7E3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 19:32:38.984892 :CAC6E7E4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 19:32:38.984892 :CAC6E7E5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 19:32:38.984892 :CAC6E7E6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 19:32:38.984893 :CAC6E7E7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 19:32:38.985392 :CAC6E7E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 19:32:38.985442 :CAC6E7F0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 19:42:39.732385 :CAC7ACF3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 19:42:40.096864 :CAC7AD31:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 19:42:40.091 2025-09-08 19:42:40.096866 :CAC7AD32:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21312 pso_num=22 pso_serial#=37 2025-09-08 19:42:40.097007 :CAC7AD33:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21312 osp_idx=0 osp_ver=586864 osp_pg=0 (spawn #572176) 2025-09-08 19:42:40.097008 :CAC7AD34:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 19:42:40.097008 :CAC7AD35:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=30 time=1746211408 2025-09-08 19:42:40.097008 :CAC7AD36:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 19:42:40.097009 :CAC7AD37:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 19:42:40.097009 :CAC7AD38:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 19:42:40.097009 :CAC7AD39:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 19:42:40.097009 :CAC7AD3A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 19:42:40.097510 :CAC7AD3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 19:42:40.097562 :CAC7AD43:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 19:52:40.990311 :CAC871D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 19:52:41.260774 :CAC87210:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 19:52:41.256 2025-09-08 19:52:41.260776 :CAC87211:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18948 pso_num=22 pso_serial#=38 2025-09-08 19:52:41.260910 :CAC87212:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18948 osp_idx=0 osp_ver=586865 osp_pg=0 (spawn #572198) 2025-09-08 19:52:41.260910 :CAC87213:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 19:52:41.260911 :CAC87214:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=31 time=1746812565 2025-09-08 19:52:41.260911 :CAC87215:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 19:52:41.260912 :CAC87216:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 19:52:41.260912 :CAC87217:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 19:52:41.260912 :CAC87218:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 19:52:41.260913 :CAC87219:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 19:52:41.261402 :CAC8721A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 19:52:41.261452 :CAC87220:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:02:42.098205 :CAC939CC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 20:02:42.399744 :CAC93A11:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 20:02:42.395 2025-09-08 20:02:42.399746 :CAC93A12:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21996 pso_num=22 pso_serial#=39 2025-09-08 20:02:42.399891 :CAC93A13:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21996 osp_idx=0 osp_ver=586866 osp_pg=0 (spawn #572224) 2025-09-08 20:02:42.399891 :CAC93A14:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 20:02:42.399892 :CAC93A15:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=32 time=1747413705 2025-09-08 20:02:42.399892 :CAC93A16:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 20:02:42.399893 :CAC93A17:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 20:02:42.399893 :CAC93A18:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 20:02:42.399893 :CAC93A19:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 20:02:42.399893 :CAC93A1A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 20:02:42.400385 :CAC93A1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 20:02:42.400434 :CAC93A23:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:12:43.394326 :CACA0749:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 20:12:43.680458 :CACA0780:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 20:12:43.675 2025-09-08 20:12:43.680460 :CACA0781:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7280 pso_num=22 pso_serial#=40 2025-09-08 20:12:43.680609 :CACA0782:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7280 osp_idx=0 osp_ver=586867 osp_pg=0 (spawn #572247) 2025-09-08 20:12:43.680610 :CACA0783:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 20:12:43.680610 :CACA0784:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=33 time=1748014987 2025-09-08 20:12:43.680610 :CACA0785:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 20:12:43.680611 :CACA0786:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 20:12:43.680611 :CACA0787:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 20:12:43.680611 :CACA0788:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 20:12:43.680612 :CACA0789:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 20:12:43.681103 :CACA078A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 20:12:43.681147 :CACA0793:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:15:48.899550 :CACA44D1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:22:44.804227 :CACAD37B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:32:45.631645 :CACB9BEB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 20:32:45.964449 :CACB9C21:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 20:32:45.959 2025-09-08 20:32:45.964451 :CACB9C22:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6020 pso_num=22 pso_serial#=41 2025-09-08 20:32:45.964590 :CACB9C23:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6020 osp_idx=0 osp_ver=586868 osp_pg=0 (spawn #572291) 2025-09-08 20:32:45.964590 :CACB9C24:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 20:32:45.964590 :CACB9C25:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=34 time=1749217268 2025-09-08 20:32:45.964591 :CACB9C26:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 20:32:45.964591 :CACB9C27:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 20:32:45.964591 :CACB9C28:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 20:32:45.964592 :CACB9C29:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 20:32:45.964592 :CACB9C2A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 20:32:45.965096 :CACB9C2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 20:32:45.965145 :CACB9C33:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:42:46.764607 :CACC65EA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 20:42:47.066406 :CACC6625:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 20:42:47.061 2025-09-08 20:42:47.066408 :CACC6626:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23820 pso_num=22 pso_serial#=42 2025-09-08 20:42:47.066548 :CACC6627:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23820 osp_idx=0 osp_ver=586869 osp_pg=0 (spawn #572314) 2025-09-08 20:42:47.066549 :CACC6628:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 20:42:47.066550 :CACC6629:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=35 time=1749818377 2025-09-08 20:42:47.066550 :CACC662A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 20:42:47.066550 :CACC662B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 20:42:47.066551 :CACC662C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 20:42:47.066552 :CACC662D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 20:42:47.066552 :CACC662E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 20:42:47.067040 :CACC662F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 20:42:47.067089 :CACC6638:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 20:52:47.876404 :CACD2F14:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 20:52:48.162803 :CACD2F50:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 20:52:48.157 2025-09-08 20:52:48.162806 :CACD2F51:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21428 pso_num=22 pso_serial#=43 2025-09-08 20:52:48.162996 :CACD2F52:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21428 osp_idx=0 osp_ver=586870 osp_pg=0 (spawn #572337) 2025-09-08 20:52:48.162996 :CACD2F53:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 20:52:48.162997 :CACD2F54:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=36 time=1750419471 2025-09-08 20:52:48.162997 :CACD2F55:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 20:52:48.162998 :CACD2F56:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 20:52:48.162998 :CACD2F57:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 20:52:48.162999 :CACD2F58:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 20:52:48.162999 :CACD2F59:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 20:52:48.163548 :CACD2F5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 20:52:48.163595 :CACD2F62:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 21:02:49.061814 :CACDF9BA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 21:02:49.394576 :CACDF9EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 21:02:49.389 2025-09-08 21:02:49.394578 :CACDF9EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17160 pso_num=22 pso_serial#=44 2025-09-08 21:02:49.394729 :CACDF9F0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17160 osp_idx=0 osp_ver=586871 osp_pg=0 (spawn #572363) 2025-09-08 21:02:49.394730 :CACDF9F1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 21:02:49.394730 :CACDF9F2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=37 time=1751020705 2025-09-08 21:02:49.394731 :CACDF9F3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 21:02:49.394731 :CACDF9F4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 21:02:49.394732 :CACDF9F5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 21:02:49.394732 :CACDF9F6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 21:02:49.394733 :CACDF9F7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 21:02:49.395274 :CACDF9F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 21:02:49.395318 :CACDFA00:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 21:12:50.156097 :CACECAA7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 21:12:50.629648 :CACECAE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 21:12:50.625 2025-09-08 21:12:50.629649 :CACECAE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24332 pso_num=22 pso_serial#=45 2025-09-08 21:12:50.629791 :CACECAE2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24332 osp_idx=0 osp_ver=586872 osp_pg=0 (spawn #572386) 2025-09-08 21:12:50.629791 :CACECAE3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 21:12:50.629792 :CACECAE4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=38 time=1751621940 2025-09-08 21:12:50.629792 :CACECAE5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 21:12:50.629793 :CACECAE6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 21:12:50.629793 :CACECAE7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 21:12:50.629794 :CACECAE8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 21:12:50.629794 :CACECAE9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 21:12:50.630280 :CACECAEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 21:12:50.630324 :CACECAF1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 21:22:51.410239 :CACF9341:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 21:22:51.774383 :CACF9386:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 21:22:51.769 2025-09-08 21:22:51.774385 :CACF9387:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24200 pso_num=22 pso_serial#=46 2025-09-08 21:22:51.774522 :CACF9388:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24200 osp_idx=0 osp_ver=586873 osp_pg=0 (spawn #572408) 2025-09-08 21:22:51.774523 :CACF9389:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 21:22:51.774524 :CACF938A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=39 time=1752223080 2025-09-08 21:22:51.774524 :CACF938B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 21:22:51.774524 :CACF938C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 21:22:51.774525 :CACF938D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 21:22:51.774525 :CACF938E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 21:22:51.774525 :CACF938F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 21:22:51.775028 :CACF9390:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 21:22:51.775084 :CACF9396:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 21:32:52.624052 :CAD05C36:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 21:32:52.988212 :CAD05C6F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 21:32:52.983 2025-09-08 21:32:52.988214 :CAD05C70:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22712 pso_num=22 pso_serial#=47 2025-09-08 21:32:52.988351 :CAD05C71:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22712 osp_idx=0 osp_ver=586874 osp_pg=0 (spawn #572431) 2025-09-08 21:32:52.988351 :CAD05C72:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 21:32:52.988352 :CAD05C73:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=1752824299 2025-09-08 21:32:52.988352 :CAD05C74:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 21:32:52.988352 :CAD05C75:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 21:32:52.988353 :CAD05C76:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 21:32:52.988353 :CAD05C77:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 21:32:52.988353 :CAD05C78:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 21:32:52.988871 :CAD05C79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 21:32:52.988914 :CAD05C80:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 21:42:53.844557 :CAD123D6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 21:42:54.318380 :CAD1241C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 21:42:54.313 2025-09-08 21:42:54.318383 :CAD1241D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16548 pso_num=22 pso_serial#=48 2025-09-08 21:42:54.318539 :CAD1241E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16548 osp_idx=0 osp_ver=586875 osp_pg=0 (spawn #572454) 2025-09-08 21:42:54.318540 :CAD1241F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 21:42:54.318540 :CAD12420:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=1753425627 2025-09-08 21:42:54.318541 :CAD12421:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 21:42:54.318541 :CAD12422:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 21:42:54.318542 :CAD12423:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 21:42:54.318542 :CAD12424:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 21:42:54.318542 :CAD12425:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 21:42:54.319069 :CAD12426:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 21:42:54.319112 :CAD1242D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-08 21:52:55.161233 :CAD1EC3D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-08 21:52:55.634654 :CAD1EC76:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-08 21:52:55.630 2025-09-08 21:52:55.634656 :CAD1EC77:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24120 pso_num=22 pso_serial#=49 2025-09-08 21:52:55.634800 :CAD1EC78:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24120 osp_idx=0 osp_ver=586876 osp_pg=0 (spawn #572476) 2025-09-08 21:52:55.634800 :CAD1EC79:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 21:52:55.634801 :CAD1EC7A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=1754026940 2025-09-08 21:52:55.634801 :CAD1EC7B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 21:52:55.634802 :CAD1EC7C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 21:52:55.634802 :CAD1EC7D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 21:52:55.634802 :CAD1EC7E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 21:52:55.634803 :CAD1EC7F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 21:52:55.635342 :CAD1EC80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 21:52:55.635391 :CAD1EC89:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 24120, W000)