Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250517060008\orcl_w000_12424_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:37354M/63366M, Ph+PgF:44543M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12424, image: ORACLE.EXE (W000) *** 2025-05-17 06:00:08.379 *** SESSION ID:(177.12045) 2025-05-17 06:00:08.379 *** 2025-05-17 06:00:08.379 Process diagnostic dump for ORACLE.EXE (W000), OS id=12424, pid: 22, proc_ser: 69, sid: 177, sess_ser: 12045 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=4.008634 sec, exc=4.008634 sec, total=4.008634 sec wait times: max=5.000000 sec, heur=4 min 29 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.000028 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.014757 sec, exc=5.014757 sec, total=5.014757 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=5.011006 sec, exc=5.011006 sec, total=5.011006 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=5.010183 sec, exc=5.010183 sec, total=5.010183 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=5.014738 sec, exc=5.014738 sec, total=5.014738 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.008311 sec, exc=5.008311 sec, total=5.008311 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=5.010039 sec, exc=5.010039 sec, total=5.010039 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=5.008465 sec, exc=5.008465 sec, total=5.008465 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=5.012422 sec, exc=5.012422 sec, total=5.012422 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 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=44 seq_num=45 snap_id=1 wait times: snap=5.014728 sec, exc=5.014728 sec, total=5.014728 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 12045 --------------------------------------------------- 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, 05:58:08 - 06:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-17 06:00:08.379 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12424, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-17 03:25:18.677621 :981DED36:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 03:25:18.677622 :981DED37:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 03:25:18.678126 :981DED38: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-05-17 03:25:18.678174 :981DED42: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-05-17 03:35:19.671953 :981EB1BB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 03:35:19.957857 :981EB21C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 03:35:19.952 2025-05-17 03:35:19.957859 :981EB21D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14204 pso_num=22 pso_serial#=56 2025-05-17 03:35:19.958000 :981EB21E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14204 osp_idx=0 osp_ver=202271 osp_pg=0 (spawn #189935) 2025-05-17 03:35:19.958001 :981EB21F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 03:35:19.958001 :981EB220:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=221 time=428729829 2025-05-17 03:35:19.958002 :981EB221:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 03:35:19.958002 :981EB222:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 03:35:19.958002 :981EB223:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 03:35:19.958003 :981EB224:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 03:35:19.958003 :981EB225:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 03:35:19.958495 :981EB226: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-05-17 03:35:19.958555 :981EB230: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-05-17 03:45:20.945101 :981F772E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 03:45:21.168500 :981F7762:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 03:45:21.164 2025-05-17 03:45:21.168502 :981F7763:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10024 pso_num=22 pso_serial#=57 2025-05-17 03:45:21.168644 :981F7764:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10024 osp_idx=0 osp_ver=202272 osp_pg=0 (spawn #189957) 2025-05-17 03:45:21.168645 :981F7765:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 03:45:21.168646 :981F7766:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=222 time=429331032 2025-05-17 03:45:21.168646 :981F7767:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 03:45:21.168646 :981F7768:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 03:45:21.168647 :981F7769:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 03:45:21.168647 :981F776A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 03:45:21.168647 :981F776B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 03:45:21.169125 :981F776C: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-05-17 03:45:21.169173 :981F7775: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-05-17 03:55:22.223695 :98203C08:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 03:55:22.494053 :98203C3A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 03:55:22.489 2025-05-17 03:55:22.494055 :98203C3B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13352 pso_num=22 pso_serial#=58 2025-05-17 03:55:22.494190 :98203C3C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13352 osp_idx=0 osp_ver=202273 osp_pg=0 (spawn #189980) 2025-05-17 03:55:22.494191 :98203C3D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 03:55:22.494192 :98203C3E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=223 time=429932360 2025-05-17 03:55:22.494192 :98203C3F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 03:55:22.494192 :98203C40:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 03:55:22.494193 :98203C41:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 03:55:22.494193 :98203C42:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 03:55:22.494193 :98203C43:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 03:55:22.494691 :98203C44: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-05-17 03:55:22.494735 :98203C4C: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-05-17 04:05:23.444364 :9821092E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 04:05:23.792831 :98210963:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 04:05:23.788 2025-05-17 04:05:23.792833 :98210964:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14284 pso_num=22 pso_serial#=59 2025-05-17 04:05:23.792972 :98210965:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14284 osp_idx=0 osp_ver=202274 osp_pg=0 (spawn #190006) 2025-05-17 04:05:23.792972 :98210966:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 04:05:23.792973 :98210967:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=430533657 2025-05-17 04:05:23.792973 :98210968:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 04:05:23.792974 :98210969:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 04:05:23.792975 :9821096A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 04:05:23.792975 :9821096B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 04:05:23.792975 :9821096C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 04:05:23.793476 :9821096D: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-05-17 04:05:23.793520 :98210975: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-05-17 04:15:24.752361 :9821D09F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 04:15:25.053992 :9821D0E2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 04:15:25.049 2025-05-17 04:15:25.053994 :9821D0E3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12004 pso_num=22 pso_serial#=60 2025-05-17 04:15:25.054124 :9821D0E4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12004 osp_idx=0 osp_ver=202275 osp_pg=0 (spawn #190028) 2025-05-17 04:15:25.054125 :9821D0E5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 04:15:25.054125 :9821D0E6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=431134922 2025-05-17 04:15:25.054126 :9821D0E7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 04:15:25.054126 :9821D0E8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 04:15:25.054126 :9821D0E9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 04:15:25.054127 :9821D0EA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 04:15:25.054127 :9821D0EB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 04:15:25.054611 :9821D0EC: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-05-17 04:15:25.054659 :9821D0F6: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-05-17 04:25:26.015476 :98229869:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 04:25:26.457799 :982298D4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 04:25:26.453 2025-05-17 04:25:26.457801 :982298D5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13384 pso_num=22 pso_serial#=61 2025-05-17 04:25:26.457939 :982298D6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13384 osp_idx=0 osp_ver=202276 osp_pg=0 (spawn #190051) 2025-05-17 04:25:26.457940 :982298D7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 04:25:26.457940 :982298D8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=226 time=431736329 2025-05-17 04:25:26.457940 :982298D9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 04:25:26.457941 :982298DA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 04:25:26.457941 :982298DB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 04:25:26.457941 :982298DC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 04:25:26.457942 :982298DD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 04:25:26.458500 :982298DE: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-05-17 04:25:26.458552 :982298E6: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-05-17 04:35:27.417330 :98235CF6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 04:35:27.750157 :98235D4D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 04:35:27.746 2025-05-17 04:35:27.750159 :98235D4E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10716 pso_num=22 pso_serial#=62 2025-05-17 04:35:27.750295 :98235D4F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10716 osp_idx=0 osp_ver=202277 osp_pg=0 (spawn #190074) 2025-05-17 04:35:27.750296 :98235D50:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 04:35:27.750296 :98235D51:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=227 time=432337625 2025-05-17 04:35:27.750297 :98235D52:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 04:35:27.750297 :98235D53:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 04:35:27.750298 :98235D54:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 04:35:27.750298 :98235D55:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 04:35:27.750298 :98235D56:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 04:35:27.750780 :98235D57: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-05-17 04:35:27.750826 :98235D60: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-05-17 04:45:28.700450 :982422EC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 04:45:28.986399 :9824233D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 04:45:28.981 2025-05-17 04:45:28.986401 :9824233E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14020 pso_num=22 pso_serial#=63 2025-05-17 04:45:28.986538 :9824233F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14020 osp_idx=0 osp_ver=202278 osp_pg=0 (spawn #190096) 2025-05-17 04:45:28.986539 :98242340:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 04:45:28.986541 :98242341:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=228 time=432938860 2025-05-17 04:45:28.986541 :98242342:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 04:45:28.986541 :98242343:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 04:45:28.986541 :98242344:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 04:45:28.986541 :98242345:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 04:45:28.986542 :98242346:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 04:45:28.987022 :98242347: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-05-17 04:45:28.987075 :98242351: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-05-17 04:55:29.959331 :9824E837:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 04:55:30.370409 :9824E89B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 04:55:30.365 2025-05-17 04:55:30.370412 :9824E89C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13824 pso_num=22 pso_serial#=64 2025-05-17 04:55:30.370546 :9824E89D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13824 osp_idx=0 osp_ver=202279 osp_pg=0 (spawn #190119) 2025-05-17 04:55:30.370547 :9824E89E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 04:55:30.370547 :9824E89F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=229 time=433540235 2025-05-17 04:55:30.370547 :9824E8A0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 04:55:30.370548 :9824E8A1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 04:55:30.370548 :9824E8A2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 04:55:30.370548 :9824E8A3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 04:55:30.370549 :9824E8A4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 04:55:30.371040 :9824E8A5: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-05-17 04:55:30.371087 :9824E8AD: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-05-17 05:05:31.327770 :9825B4FE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 05:05:31.801470 :9825B576:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 05:05:31.796 2025-05-17 05:05:31.801472 :9825B577:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9148 pso_num=22 pso_serial#=65 2025-05-17 05:05:31.801609 :9825B578:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9148 osp_idx=0 osp_ver=202280 osp_pg=0 (spawn #190145) 2025-05-17 05:05:31.801610 :9825B579:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 05:05:31.801610 :9825B57A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=230 time=434141672 2025-05-17 05:05:31.801610 :9825B57B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 05:05:31.801611 :9825B57C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 05:05:31.801611 :9825B57D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 05:05:31.801611 :9825B57E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 05:05:31.801611 :9825B57F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 05:05:31.802119 :9825B580: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-05-17 05:05:31.802167 :9825B58A: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-05-17 05:06:00.093047 :9825BEDC: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-05-17 05:15:33.214098 :98267AD2: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-05-17 05:25:34.215634 :982741E4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 05:25:34.610908 :9827423E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 05:25:34.606 2025-05-17 05:25:34.610910 :9827423F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11492 pso_num=22 pso_serial#=66 2025-05-17 05:25:34.611049 :98274240:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11492 osp_idx=0 osp_ver=202281 osp_pg=0 (spawn #190189) 2025-05-17 05:25:34.611050 :98274241:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 05:25:34.611050 :98274242:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=231 time=435344485 2025-05-17 05:25:34.611050 :98274243:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 05:25:34.611051 :98274244:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 05:25:34.611051 :98274245:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 05:25:34.611051 :98274246:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 05:25:34.611052 :98274247:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 05:25:34.611543 :98274248: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-05-17 05:25:34.611597 :98274250: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-05-17 05:35:35.478325 :98280787:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 05:35:35.998745 :982807E4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 05:35:35.993 2025-05-17 05:35:35.998747 :982807E5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13308 pso_num=22 pso_serial#=67 2025-05-17 05:35:35.998909 :982807E6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13308 osp_idx=0 osp_ver=202282 osp_pg=0 (spawn #190212) 2025-05-17 05:35:35.998909 :982807E7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 05:35:35.998910 :982807E8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=232 time=435945875 2025-05-17 05:35:35.998910 :982807E9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 05:35:35.998911 :982807EA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 05:35:35.998911 :982807EB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 05:35:35.998912 :982807EC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 05:35:35.998912 :982807ED:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 05:35:35.999445 :982807EE: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-05-17 05:35:35.999492 :982807F6: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-05-17 05:45:36.866465 :9828CC0E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 05:45:37.506342 :9828CC67:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 05:45:37.502 2025-05-17 05:45:37.506344 :9828CC68:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12420 pso_num=22 pso_serial#=68 2025-05-17 05:45:37.506480 :9828CC69:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12420 osp_idx=0 osp_ver=202283 osp_pg=0 (spawn #190234) 2025-05-17 05:45:37.506481 :9828CC6A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 05:45:37.506481 :9828CC6B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=233 time=436547375 2025-05-17 05:45:37.506481 :9828CC6C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 05:45:37.506482 :9828CC6D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 05:45:37.506482 :9828CC6E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 05:45:37.506483 :9828CC6F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 05:45:37.506483 :9828CC70:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 05:45:37.506968 :9828CC71: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-05-17 05:45:37.507019 :9828CC7B: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-05-17 05:55:38.377427 :9829920A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-17 05:55:38.960150 :9829926C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-17 05:55:38.955 2025-05-17 05:55:38.960152 :9829926D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12424 pso_num=22 pso_serial#=69 2025-05-17 05:55:38.960338 :9829926E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12424 osp_idx=0 osp_ver=202284 osp_pg=0 (spawn #190257) 2025-05-17 05:55:38.960338 :9829926F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-17 05:55:38.960339 :98299270:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=234 time=437148829 2025-05-17 05:55:38.960340 :98299271:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-17 05:55:38.960340 :98299272:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-17 05:55:38.960340 :98299273:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-17 05:55:38.960341 :98299274:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-17 05:55:38.960341 :98299275:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-17 05:55:38.960859 :98299276: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-05-17 05:55:38.960906 :9829927E: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: 12424, W000)