Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916082601\orcl_w000_24660_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:34707M/63366M, Ph+PgF:39264M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 24660, image: ORACLE.EXE (W000) *** 2025-09-16 08:26:01.116 *** SESSION ID:(177.13081) 2025-09-16 08:26:01.116 *** 2025-09-16 08:26:01.116 Process diagnostic dump for ORACLE.EXE (W000), OS id=24660, pid: 22, proc_ser: 141, sid: 177, sess_ser: 13081 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.271801 sec, exc=0.271801 sec, total=0.271801 sec wait times: max=5.000000 sec, heur=1 min 10 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.000029 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.004589 sec, exc=5.004589 sec, total=5.004589 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000041 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=5.007272 sec, exc=5.007272 sec, total=5.007272 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=12 seq_num=13 snap_id=1 wait times: snap=5.001370 sec, exc=5.001370 sec, total=5.001370 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.014512 sec, exc=5.014512 sec, total=5.014512 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.014391 sec, exc=5.014391 sec, total=5.014391 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.014735 sec, exc=5.014735 sec, total=5.014735 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000025 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: 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.004622 sec, exc=5.004622 sec, total=5.004622 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: 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.008704 sec, exc=5.008704 sec, total=5.008704 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 177 serial 13081 --------------------------------------------------- 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 --------------------------------------------------- [71 samples, 08:24:50 - 08:26:01] idle wait at each sample [session created at: 08:24:50] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-16 08:26:01.116 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 24660, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-16 06:04:32.574422 :CE11B379:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 06:04:32.574973 :CE11B37A: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-16 06:04:32.575028 :CE11B380: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-16 06:14:33.363843 :CE12832F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 06:14:33.775099 :CE12838A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 06:14:33.770 2025-09-16 06:14:33.775101 :CE12838B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24032 pso_num=22 pso_serial#=127 2025-09-16 06:14:33.775244 :CE12838C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24032 osp_idx=0 osp_ver=613474 osp_pg=0 (spawn #596997) 2025-09-16 06:14:33.775245 :CE12838D: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-16 06:14:33.775245 :CE12838E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=151 time=2388925080 2025-09-16 06:14:33.775246 :CE12838F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 06:14:33.775246 :CE128390:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 06:14:33.775246 :CE128391:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 06:14:33.775247 :CE128392:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 06:14:33.775247 :CE128393:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 06:14:33.775822 :CE128394: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-16 06:14:33.775871 :CE12839B: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-16 06:24:34.708697 :CE134BEC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 06:24:35.119898 :CE134C6E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 06:24:35.114 2025-09-16 06:24:35.119900 :CE134C6F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25056 pso_num=22 pso_serial#=128 2025-09-16 06:24:35.120049 :CE134C70:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25056 osp_idx=0 osp_ver=613475 osp_pg=0 (spawn #597020) 2025-09-16 06:24:35.120049 :CE134C71: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-16 06:24:35.120050 :CE134C72:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=152 time=2389526424 2025-09-16 06:24:35.120050 :CE134C73:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 06:24:35.120051 :CE134C74:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 06:24:35.120051 :CE134C75:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 06:24:35.120051 :CE134C76:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 06:24:35.120051 :CE134C77:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 06:24:35.120563 :CE134C78: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-16 06:24:35.120611 :CE134C7E: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-16 06:34:36.023629 :CE1413D3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 06:34:36.419024 :CE141441:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 06:34:36.414 2025-09-16 06:34:36.419026 :CE141442:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23292 pso_num=22 pso_serial#=129 2025-09-16 06:34:36.419164 :CE141443:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23292 osp_idx=0 osp_ver=613476 osp_pg=0 (spawn #597043) 2025-09-16 06:34:36.419165 :CE141444: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-16 06:34:36.419165 :CE141445:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=153 time=2390127721 2025-09-16 06:34:36.419166 :CE141446:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 06:34:36.419166 :CE141447:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 06:34:36.419166 :CE141448:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 06:34:36.419167 :CE141449:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 06:34:36.419167 :CE14144A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 06:34:36.419746 :CE14144B: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-16 06:34:36.419791 :CE141454: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-16 06:42:17.649289 :CE14AE5F: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-16 06:42:17.651431 :CE14AE61: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-16 06:44:37.750479 :CE14DDBF: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-16 06:54:38.661345 :CE15A5B8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 06:54:39.072157 :CE15A631:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 06:54:39.067 2025-09-16 06:54:39.072158 :CE15A632:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18376 pso_num=22 pso_serial#=130 2025-09-16 06:54:39.072303 :CE15A633:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18376 osp_idx=0 osp_ver=613477 osp_pg=0 (spawn #597087) 2025-09-16 06:54:39.072304 :CE15A634: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-16 06:54:39.072304 :CE15A635:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=154 time=2391330377 2025-09-16 06:54:39.072305 :CE15A636:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 06:54:39.072305 :CE15A637:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 06:54:39.072305 :CE15A638:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 06:54:39.072306 :CE15A639:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 06:54:39.072306 :CE15A63A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 06:54:39.072817 :CE15A63B: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-16 06:54:39.072869 :CE15A642: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-16 07:04:39.990250 :CE167307:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 07:04:40.322772 :CE16737D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 07:04:40.318 2025-09-16 07:04:40.322774 :CE16737E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20116 pso_num=22 pso_serial#=131 2025-09-16 07:04:40.322912 :CE16737F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20116 osp_idx=0 osp_ver=613478 osp_pg=0 (spawn #597113) 2025-09-16 07:04:40.322912 :CE167380: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-16 07:04:40.322913 :CE167381:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=155 time=2391931627 2025-09-16 07:04:40.322913 :CE167382:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:04:40.322914 :CE167383:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:04:40.322914 :CE167384:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:04:40.322914 :CE167385:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:04:40.322914 :CE167386:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:04:40.323389 :CE167387: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-16 07:04:40.323435 :CE16738F: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-16 07:14:41.242521 :CE17418F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 07:14:41.621649 :CE1741F9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 07:14:41.617 2025-09-16 07:14:41.621651 :CE1741FA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19144 pso_num=22 pso_serial#=132 2025-09-16 07:14:41.621790 :CE1741FB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19144 osp_idx=0 osp_ver=613479 osp_pg=0 (spawn #597135) 2025-09-16 07:14:41.621791 :CE1741FC: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-16 07:14:41.621791 :CE1741FD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=156 time=2392532924 2025-09-16 07:14:41.621792 :CE1741FE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:14:41.621792 :CE1741FF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:14:41.621792 :CE174200:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:14:41.621793 :CE174201:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:14:41.621793 :CE174202:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:14:41.622294 :CE174203: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-16 07:14:41.622352 :CE17420B: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-16 07:24:42.429352 :CE180A4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 07:24:42.996525 :CE180ABD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 07:24:42.991 2025-09-16 07:24:42.996527 :CE180ABE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25020 pso_num=22 pso_serial#=133 2025-09-16 07:24:42.996676 :CE180ABF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25020 osp_idx=0 osp_ver=613480 osp_pg=0 (spawn #597158) 2025-09-16 07:24:42.996677 :CE180AC0: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-16 07:24:42.996677 :CE180AC1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=157 time=2393134299 2025-09-16 07:24:42.996678 :CE180AC2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:24:42.996678 :CE180AC3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:24:42.996678 :CE180AC4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:24:42.996679 :CE180AC5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:24:42.996679 :CE180AC6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:24:42.997189 :CE180AC7: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-16 07:24:42.997235 :CE180ACF: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-16 07:34:43.869341 :CE18D2A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 07:34:44.311766 :CE18D314:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 07:34:44.306 2025-09-16 07:34:44.311769 :CE18D315:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16724 pso_num=22 pso_serial#=134 2025-09-16 07:34:44.311918 :CE18D316:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16724 osp_idx=0 osp_ver=613481 osp_pg=0 (spawn #597181) 2025-09-16 07:34:44.311919 :CE18D317: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-16 07:34:44.311919 :CE18D318:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=158 time=2393735612 2025-09-16 07:34:44.311920 :CE18D319:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:34:44.311921 :CE18D31A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:34:44.311921 :CE18D31B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:34:44.311921 :CE18D31C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:34:44.311922 :CE18D31D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:34:44.312485 :CE18D31E: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-16 07:34:44.312547 :CE18D326: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-16 07:44:45.225213 :CE199C0A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 07:44:45.698431 :CE199C80:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 07:44:45.694 2025-09-16 07:44:45.698433 :CE199C81:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20732 pso_num=22 pso_serial#=135 2025-09-16 07:44:45.698574 :CE199C82:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20732 osp_idx=0 osp_ver=613482 osp_pg=0 (spawn #597203) 2025-09-16 07:44:45.698575 :CE199C83: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-16 07:44:45.698575 :CE199C84:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=159 time=2394337002 2025-09-16 07:44:45.698576 :CE199C85:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:44:45.698576 :CE199C86:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:44:45.698576 :CE199C87:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:44:45.698577 :CE199C88:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:44:45.698577 :CE199C89:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:44:45.699074 :CE199C8A: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-16 07:44:45.699117 :CE199C92: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-16 07:54:46.570952 :CE1A64C3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 07:54:47.044013 :CE1A6564:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 07:54:47.039 2025-09-16 07:54:47.044015 :CE1A6565:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23404 pso_num=22 pso_serial#=136 2025-09-16 07:54:47.044158 :CE1A6566:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23404 osp_idx=0 osp_ver=613483 osp_pg=0 (spawn #597226) 2025-09-16 07:54:47.044159 :CE1A6567: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-16 07:54:47.044159 :CE1A6568:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=160 time=2394938346 2025-09-16 07:54:47.044160 :CE1A6569:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 07:54:47.044160 :CE1A656A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 07:54:47.044160 :CE1A656B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 07:54:47.044161 :CE1A656C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 07:54:47.044161 :CE1A656D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 07:54:47.044682 :CE1A656E: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-16 07:54:47.044728 :CE1A6578: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-16 08:04:47.953284 :CE1B2F3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 08:04:48.287081 :CE1B2FC9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 08:04:48.281 2025-09-16 08:04:48.287083 :CE1B2FCA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24256 pso_num=22 pso_serial#=137 2025-09-16 08:04:48.287268 :CE1B2FCB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24256 osp_idx=0 osp_ver=613484 osp_pg=0 (spawn #597252) 2025-09-16 08:04:48.287269 :CE1B2FCC: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-16 08:04:48.287269 :CE1B2FCD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=161 time=2395539596 2025-09-16 08:04:48.287270 :CE1B2FCE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:04:48.287270 :CE1B2FCF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:04:48.287270 :CE1B2FD0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:04:48.287271 :CE1B2FD1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:04:48.287271 :CE1B2FD2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:04:48.287773 :CE1B2FD3: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-16 08:04:48.287817 :CE1B2FDA: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-16 08:14:49.164828 :CE1BFD5A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 08:14:49.193140 :CE1BFD5D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 08:14:49.180 2025-09-16 08:14:49.193142 :CE1BFD5E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24716 pso_num=22 pso_serial#=138 2025-09-16 08:14:49.193278 :CE1BFD5F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24716 (legacy spawn) 2025-09-16 08:14:49.199787 :CE1BFD60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 08:14:49.199962 :CE1BFD65:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-16 08:14:49.200766 :CE1BFD6A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 08:14:49.209490 :CE1BFD6D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 08:14:49.196 2025-09-16 08:14:49.209492 :CE1BFD6E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25036 pso_num=22 pso_serial#=139 2025-09-16 08:14:49.209629 :CE1BFD6F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25036 (legacy spawn) 2025-09-16 08:14:49.216180 :CE1BFD70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 08:14:49.216350 :CE1BFD78:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-16 08:14:49.217156 :CE1BFD7A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 08:14:49.544084 :CE1BFDE1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 08:14:49.540 2025-09-16 08:14:49.544086 :CE1BFDE2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24012 pso_num=22 pso_serial#=140 2025-09-16 08:14:49.544228 :CE1BFDE3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24012 osp_idx=0 osp_ver=613487 osp_pg=0 (spawn #597274) 2025-09-16 08:14:49.544228 :CE1BFDE4: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-16 08:14:49.544229 :CE1BFDE5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=162 time=2396140846 2025-09-16 08:14:49.544229 :CE1BFDE6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:14:49.544230 :CE1BFDE7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:14:49.544230 :CE1BFDE8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:14:49.544230 :CE1BFDE9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:14:49.544231 :CE1BFDEA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:14:49.544731 :CE1BFDEB: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-16 08:14:49.544778 :CE1BFDF2: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-16 08:24:50.426450 :CE1CC87C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 08:24:50.745140 :CE1CC8E2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 08:24:50.745 2025-09-16 08:24:50.745142 :CE1CC8E3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24660 pso_num=22 pso_serial#=141 2025-09-16 08:24:50.745283 :CE1CC8E4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24660 osp_idx=0 osp_ver=613488 osp_pg=0 (spawn #597297) 2025-09-16 08:24:50.745284 :CE1CC8E5: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-16 08:24:50.745284 :CE1CC8E6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=163 time=2396742049 2025-09-16 08:24:50.745284 :CE1CC8E7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 08:24:50.745285 :CE1CC8E8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 08:24:50.745285 :CE1CC8E9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 08:24:50.745285 :CE1CC8EA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 08:24:50.745286 :CE1CC8EB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 08:24:50.745791 :CE1CC8EC: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-16 08:24:50.745840 :CE1CC8F4: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: 24660, W000)