Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816060010\orcl_w000_1400_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:35466M/63366M, Ph+PgF:40794M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 1400, image: ORACLE.EXE (W000) *** 2025-08-16 06:00:10.624 *** SESSION ID:(177.54011) 2025-08-16 06:00:10.624 *** 2025-08-16 06:00:10.624 Process diagnostic dump for ORACLE.EXE (W000), OS id=1400, pid: 22, proc_ser: 253, sid: 177, sess_ser: 54011 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=103 seq_num=104 snap_id=1 wait times: snap=4.063016 sec, exc=4.063016 sec, total=4.063016 sec wait times: max=5.000000 sec, heur=8 min 34 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=102 seq_num=103 snap_id=1 wait times: snap=5.003224 sec, exc=5.003224 sec, total=5.003224 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=101 seq_num=102 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.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=100 seq_num=101 snap_id=1 wait times: snap=5.004669 sec, exc=5.004669 sec, total=5.004669 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=99 seq_num=100 snap_id=1 wait times: snap=5.004614 sec, exc=5.004614 sec, total=5.004614 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=98 seq_num=99 snap_id=1 wait times: snap=5.008334 sec, exc=5.008334 sec, total=5.008334 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=97 seq_num=98 snap_id=1 wait times: snap=5.000377 sec, exc=5.000377 sec, total=5.000377 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=96 seq_num=97 snap_id=1 wait times: snap=5.013530 sec, exc=5.013530 sec, total=5.013530 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=95 seq_num=96 snap_id=1 wait times: snap=5.000956 sec, exc=5.000956 sec, total=5.000956 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=5.004623 sec, exc=5.004623 sec, total=5.004623 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 54011 --------------------------------------------------- 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:10 - 06:00:10] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 06:00:10.624 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 1400, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 03:31:17.424344 :C052F5F8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19516 pso_num=22 pso_serial#=239 2025-08-16 03:31:17.424480 :C052F5F9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19516 (legacy spawn) 2025-08-16 03:31:17.430955 :C052F5FA: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-08-16 03:31:17.431126 :C052F603: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-08-16 03:31:17.431945 :C052F610:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 03:31:17.437422 :C052F63F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 03:31:17.432 2025-08-16 03:31:17.437425 :C052F640:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21244 pso_num=22 pso_serial#=240 2025-08-16 03:31:17.437556 :C052F641:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21244 osp_idx=0 osp_ver=519492 osp_pg=0 (spawn #493246) 2025-08-16 03:31:17.437557 :C052F642:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 03:31:17.437558 :C052F643:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=3995746736 2025-08-16 03:31:17.437558 :C052F644:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 03:31:17.437558 :C052F645:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 03:31:17.437559 :C052F646:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 03:31:17.437559 :C052F647:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 03:31:17.437560 :C052F648:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 03:31:17.438086 :C052F649: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-08-16 03:31:17.438129 :C052F650: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-08-16 03:41:18.421327 :C053BC03:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 03:41:18.831630 :C053BC44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 03:41:18.827 2025-08-16 03:41:18.831633 :C053BC45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22184 pso_num=22 pso_serial#=241 2025-08-16 03:41:18.831780 :C053BC46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22184 osp_idx=0 osp_ver=519493 osp_pg=0 (spawn #493267) 2025-08-16 03:41:18.831781 :C053BC47:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 03:41:18.831781 :C053BC48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=3996348142 2025-08-16 03:41:18.831782 :C053BC49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 03:41:18.831782 :C053BC4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 03:41:18.831782 :C053BC4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 03:41:18.831782 :C053BC4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 03:41:18.831782 :C053BC4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 03:41:18.832291 :C053BC4E: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-08-16 03:41:18.832336 :C053BC56: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-08-16 03:51:19.795599 :C0548273:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 03:51:20.175568 :C05482B5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 03:51:20.170 2025-08-16 03:51:20.175571 :C05482B6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21772 pso_num=22 pso_serial#=242 2025-08-16 03:51:20.175701 :C05482B7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21772 osp_idx=0 osp_ver=519494 osp_pg=0 (spawn #493289) 2025-08-16 03:51:20.175702 :C05482B8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 03:51:20.175702 :C05482B9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=3996949486 2025-08-16 03:51:20.175703 :C05482BA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 03:51:20.175703 :C05482BB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 03:51:20.175704 :C05482BC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 03:51:20.175704 :C05482BD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 03:51:20.175704 :C05482BE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 03:51:20.176189 :C05482BF: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-08-16 03:51:20.176237 :C05482C5: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-08-16 04:01:21.025105 :C0554B91:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 04:01:21.468765 :C0554BD0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 04:01:21.463 2025-08-16 04:01:21.468768 :C0554BD1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21620 pso_num=22 pso_serial#=243 2025-08-16 04:01:21.468914 :C0554BD2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21620 osp_idx=0 osp_ver=519495 osp_pg=0 (spawn #493314) 2025-08-16 04:01:21.468914 :C0554BD3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 04:01:21.468915 :C0554BD4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=3997550767 2025-08-16 04:01:21.468915 :C0554BD5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 04:01:21.468916 :C0554BD6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 04:01:21.468916 :C0554BD7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 04:01:21.468917 :C0554BD8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 04:01:21.468917 :C0554BD9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 04:01:21.469439 :C0554BDA: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-08-16 04:01:21.469493 :C0554BE1: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-08-16 04:05:21.555307 :C0559A61: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-08-16 04:11:22.930358 :C0561817: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-08-16 04:21:23.777647 :C056E04F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 04:21:24.240534 :C056E094:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 04:21:24.236 2025-08-16 04:21:24.240536 :C056E095:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18812 pso_num=22 pso_serial#=244 2025-08-16 04:21:24.240683 :C056E096:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18812 osp_idx=0 osp_ver=519496 osp_pg=0 (spawn #493359) 2025-08-16 04:21:24.240684 :C056E097:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 04:21:24.240685 :C056E098:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=3998753548 2025-08-16 04:21:24.240685 :C056E099:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 04:21:24.240685 :C056E09A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 04:21:24.240686 :C056E09B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 04:21:24.240686 :C056E09C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 04:21:24.240687 :C056E09D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 04:21:24.241192 :C056E09E: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-08-16 04:21:24.241238 :C056E0A6: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-08-16 04:31:25.108007 :C057A71D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 04:31:25.643944 :C057A763:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 04:31:25.639 2025-08-16 04:31:25.643946 :C057A764:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21620 pso_num=22 pso_serial#=245 2025-08-16 04:31:25.644088 :C057A765:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21620 osp_idx=0 osp_ver=519497 osp_pg=0 (spawn #493382) 2025-08-16 04:31:25.644088 :C057A766:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 04:31:25.644089 :C057A767:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=3999354954 2025-08-16 04:31:25.644089 :C057A768:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 04:31:25.644089 :C057A769:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 04:31:25.644089 :C057A76A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 04:31:25.644090 :C057A76B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 04:31:25.644090 :C057A76C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 04:31:25.644587 :C057A76D: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-08-16 04:31:25.644631 :C057A775: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-08-16 04:41:26.428263 :C0586D8A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 04:41:26.901797 :C0586DCB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 04:41:26.897 2025-08-16 04:41:26.901799 :C0586DCC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8372 pso_num=22 pso_serial#=246 2025-08-16 04:41:26.901935 :C0586DCD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8372 osp_idx=0 osp_ver=519498 osp_pg=0 (spawn #493405) 2025-08-16 04:41:26.901935 :C0586DCE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 04:41:26.901936 :C0586DCF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=3999956204 2025-08-16 04:41:26.901936 :C0586DD0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 04:41:26.901937 :C0586DD1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 04:41:26.901937 :C0586DD2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 04:41:26.901938 :C0586DD3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 04:41:26.901938 :C0586DD4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 04:41:26.902505 :C0586DD5: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-08-16 04:41:26.902551 :C0586DDD: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-08-16 04:51:27.743055 :C05933D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 04:51:28.232532 :C059340D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 04:51:28.227 2025-08-16 04:51:28.232534 :C059340E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21300 pso_num=22 pso_serial#=247 2025-08-16 04:51:28.232695 :C059340F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21300 osp_idx=0 osp_ver=519499 osp_pg=0 (spawn #493427) 2025-08-16 04:51:28.232695 :C0593410:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 04:51:28.232696 :C0593411:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=4000557533 2025-08-16 04:51:28.232696 :C0593412:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 04:51:28.232697 :C0593413:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 04:51:28.232697 :C0593414:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 04:51:28.232697 :C0593415:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 04:51:28.232697 :C0593416:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 04:51:28.233218 :C0593417: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-08-16 04:51:28.233273 :C0593420: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-08-16 05:01:29.092105 :C059FC4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 05:01:29.534343 :C059FCB6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 05:01:29.529 2025-08-16 05:01:29.534345 :C059FCB7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21148 pso_num=22 pso_serial#=248 2025-08-16 05:01:29.534480 :C059FCB8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21148 osp_idx=0 osp_ver=519500 osp_pg=0 (spawn #493452) 2025-08-16 05:01:29.534482 :C059FCB9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 05:01:29.534482 :C059FCBA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=13 time=4001158845 2025-08-16 05:01:29.534483 :C059FCBB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:01:29.534483 :C059FCBC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:01:29.534484 :C059FCBD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:01:29.534484 :C059FCBE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:01:29.534484 :C059FCBF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:01:29.534973 :C059FCC0: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-08-16 05:01:29.535030 :C059FCC6: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-08-16 05:11:30.380582 :C05AC715:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 05:11:30.723556 :C05AC779:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 05:11:30.718 2025-08-16 05:11:30.723558 :C05AC77A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18460 pso_num=22 pso_serial#=249 2025-08-16 05:11:30.723696 :C05AC77B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18460 osp_idx=0 osp_ver=519501 osp_pg=0 (spawn #493476) 2025-08-16 05:11:30.723696 :C05AC77C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 05:11:30.723697 :C05AC77D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=14 time=4001760033 2025-08-16 05:11:30.723697 :C05AC77E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:11:30.723697 :C05AC77F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:11:30.723698 :C05AC780:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:11:30.723698 :C05AC781:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:11:30.723698 :C05AC782:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:11:30.724189 :C05AC783: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-08-16 05:11:30.724236 :C05AC78A: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-08-16 05:21:31.560385 :C05B8EAF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 05:21:32.065169 :C05B8F17:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 05:21:32.060 2025-08-16 05:21:32.065171 :C05B8F18:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6364 pso_num=22 pso_serial#=250 2025-08-16 05:21:32.065308 :C05B8F19:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6364 osp_idx=0 osp_ver=519502 osp_pg=0 (spawn #493498) 2025-08-16 05:21:32.065309 :C05B8F1A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 05:21:32.065310 :C05B8F1B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=15 time=4002361376 2025-08-16 05:21:32.065310 :C05B8F1C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:21:32.065310 :C05B8F1D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:21:32.065311 :C05B8F1E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:21:32.065311 :C05B8F1F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:21:32.065311 :C05B8F20:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:21:32.065810 :C05B8F21: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-08-16 05:21:32.065855 :C05B8F2B: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-08-16 05:31:32.977030 :C05C5375:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 05:31:33.288787 :C05C53AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 05:31:33.284 2025-08-16 05:31:33.288790 :C05C53AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18460 pso_num=22 pso_serial#=251 2025-08-16 05:31:33.288928 :C05C53AC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18460 osp_idx=0 osp_ver=519503 osp_pg=0 (spawn #493521) 2025-08-16 05:31:33.288929 :C05C53AD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 05:31:33.288929 :C05C53AE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=16 time=4002962595 2025-08-16 05:31:33.288929 :C05C53AF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:31:33.288930 :C05C53B0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:31:33.288930 :C05C53B1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:31:33.288930 :C05C53B2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:31:33.288931 :C05C53B3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:31:33.289446 :C05C53B4: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-08-16 05:31:33.289491 :C05C53BB: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-08-16 05:41:34.169359 :C05D19BC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 05:41:34.642762 :C05D19F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 05:41:34.638 2025-08-16 05:41:34.642764 :C05D19F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21776 pso_num=22 pso_serial#=252 2025-08-16 05:41:34.642904 :C05D19F2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21776 osp_idx=0 osp_ver=519504 osp_pg=0 (spawn #493544) 2025-08-16 05:41:34.642904 :C05D19F3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 05:41:34.642905 :C05D19F4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=17 time=4003563954 2025-08-16 05:41:34.642905 :C05D19F5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:41:34.642906 :C05D19F6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:41:34.642906 :C05D19F7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:41:34.642906 :C05D19F8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:41:34.642906 :C05D19F9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:41:34.643390 :C05D19FA: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-08-16 05:41:34.643445 :C05D1A02: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-08-16 05:51:35.583324 :C05DDF8F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-16 05:51:35.885233 :C05DDFC5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-16 05:51:35.879 2025-08-16 05:51:35.885235 :C05DDFC6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1400 pso_num=22 pso_serial#=253 2025-08-16 05:51:35.885374 :C05DDFC7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1400 osp_idx=0 osp_ver=519505 osp_pg=0 (spawn #493566) 2025-08-16 05:51:35.885374 :C05DDFC8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 05:51:35.885375 :C05DDFC9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=18 time=4004165189 2025-08-16 05:51:35.885376 :C05DDFCA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 05:51:35.885376 :C05DDFCB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 05:51:35.885376 :C05DDFCC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 05:51:35.885377 :C05DDFCD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 05:51:35.885377 :C05DDFCE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 05:51:35.885890 :C05DDFCF: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-08-16 05:51:35.885944 :C05DDFD6: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: 1400, W000)