Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426180652\orcl_w000_11140_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:37602M/63366M, Ph+PgF:45324M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 11140, image: ORACLE.EXE (W000) *** 2025-04-26 18:06:52.731 *** SESSION ID:(201.15541) 2025-04-26 18:06:52.731 *** 2025-04-26 18:06:52.731 Process diagnostic dump for ORACLE.EXE (W000), OS id=11140, pid: 25, proc_ser: 119, sid: 201, sess_ser: 15541 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=1.746672 sec, exc=1.746672 sec, total=1.746672 sec wait times: max=5.000000 sec, heur=5 min 52 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.010775 sec, exc=5.010775 sec, total=5.010775 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.012888 sec, exc=5.012888 sec, total=5.012888 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.010145 sec, exc=5.010145 sec, total=5.010145 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=66 seq_num=67 snap_id=1 wait times: snap=5.009759 sec, exc=5.009759 sec, total=5.009759 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=65 seq_num=66 snap_id=1 wait times: snap=5.003206 sec, exc=5.003206 sec, total=5.003206 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=64 seq_num=65 snap_id=1 wait times: snap=5.000526 sec, exc=5.000526 sec, total=5.000526 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=63 seq_num=64 snap_id=1 wait times: snap=5.001815 sec, exc=5.001815 sec, total=5.001815 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=5.011012 sec, exc=5.011012 sec, total=5.011012 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.010148 sec, exc=5.010148 sec, total=5.010148 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 201 serial 15541 --------------------------------------------------- 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, 18:04:52 - 18:06:52] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-26 18:06:52.731 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 11140, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-26 17:08:38.827560 :8F17E76F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:08:38.819 2025-04-26 17:08:38.827563 :8F17E770:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10696 pso_num=25 pso_serial#=96 2025-04-26 17:08:38.827704 :8F17E771:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10696 (legacy spawn) 2025-04-26 17:08:38.834190 :8F17E772:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:08:38.834366 :8F17E777:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:08:38.835239 :8F17E77C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:08:49.361860 :8F17EAED:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:08:49.354 2025-04-26 17:08:49.361862 :8F17EAEE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10108 pso_num=25 pso_serial#=97 2025-04-26 17:08:49.361998 :8F17EAEF:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10108 (legacy spawn) 2025-04-26 17:08:49.368459 :8F17EAF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:08:49.368677 :8F17EAF9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:08:49.369488 :8F17EAFA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:08:50.133792 :8F17EB2C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:08:50.119 2025-04-26 17:08:50.133794 :8F17EB2D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6948 pso_num=25 pso_serial#=98 2025-04-26 17:08:50.133925 :8F17EB2E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=6948 (legacy spawn) 2025-04-26 17:08:50.140336 :8F17EB2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:08:50.140513 :8F17EB34:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:08:50.141350 :8F17EB39:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:08:50.149992 :8F17EB3C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:08:50.135 2025-04-26 17:08:50.149994 :8F17EB3D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8544 pso_num=25 pso_serial#=99 2025-04-26 17:08:50.150126 :8F17EB3E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8544 (legacy spawn) 2025-04-26 17:08:50.156671 :8F17EB3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:08:50.156848 :8F17EB48:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:08:50.157695 :8F17EB49:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:08:53.388659 :8F17EC4F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:08:53.376 2025-04-26 17:08:53.388661 :8F17EC50:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8216 pso_num=25 pso_serial#=100 2025-04-26 17:08:53.388792 :8F17EC51:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=8216 (legacy spawn) 2025-04-26 17:08:53.395341 :8F17EC52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:08:53.395513 :8F17EC57:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:08:53.396322 :8F17EC5C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:08:53.414991 :8F17EC5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:08:53.407 2025-04-26 17:08:53.414993 :8F17EC60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11060 pso_num=25 pso_serial#=101 2025-04-26 17:08:53.415128 :8F17EC61:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11060 (legacy spawn) 2025-04-26 17:08:53.421532 :8F17EC62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:08:53.421698 :8F17EC6B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:08:53.422493 :8F17EC6C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:09:26.489494 :8F17F738:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:09:26.475 2025-04-26 17:09:26.489497 :8F17F739:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11204 pso_num=25 pso_serial#=102 2025-04-26 17:09:26.489629 :8F17F73A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11204 (legacy spawn) 2025-04-26 17:09:26.496033 :8F17F73B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:09:26.496212 :8F17F743:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:09:26.497045 :8F17F745:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:09:26.505706 :8F17F748:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:09:26.491 2025-04-26 17:09:26.505708 :8F17F749:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7396 pso_num=25 pso_serial#=103 2025-04-26 17:09:26.505849 :8F17F74A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7396 (legacy spawn) 2025-04-26 17:09:26.512391 :8F17F74B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:09:26.512555 :8F17F754:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:09:26.513358 :8F17F755:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:09:29.683782 :8F17F863:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:09:29.671 2025-04-26 17:09:29.683784 :8F17F864:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7212 pso_num=25 pso_serial#=104 2025-04-26 17:09:29.683914 :8F17F865:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7212 (legacy spawn) 2025-04-26 17:09:29.690396 :8F17F866:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:09:29.690574 :8F17F86B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:09:29.691373 :8F17F870:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:09:29.700021 :8F17F873:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:09:29.687 2025-04-26 17:09:29.700023 :8F17F874:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10344 pso_num=25 pso_serial#=105 2025-04-26 17:09:29.700160 :8F17F875:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10344 (legacy spawn) 2025-04-26 17:09:29.706574 :8F17F876:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:09:29.706741 :8F17F87F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:09:29.707523 :8F17F880:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:09:56.865807 :8F18016F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:09:56.857 2025-04-26 17:09:56.865809 :8F180170:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=5464 pso_num=25 pso_serial#=106 2025-04-26 17:09:56.865942 :8F180171:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=5464 (legacy spawn) 2025-04-26 17:09:56.872362 :8F180172:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:09:56.872540 :8F180177:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:09:56.873338 :8F18017C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:09:57.651941 :8F18019E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:09:57.639 2025-04-26 17:09:57.651943 :8F18019F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10296 pso_num=25 pso_serial#=107 2025-04-26 17:09:57.652078 :8F1801A0:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10296 (legacy spawn) 2025-04-26 17:09:57.658570 :8F1801AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:09:57.658733 :8F1801B5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:09:57.659516 :8F1801B6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:31.723313 :8F180D27:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:31.714 2025-04-26 17:10:31.723315 :8F180D28:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10260 pso_num=25 pso_serial#=108 2025-04-26 17:10:31.723454 :8F180D29:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10260 (legacy spawn) 2025-04-26 17:10:31.730192 :8F180D2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:10:31.730353 :8F180D30:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:10:31.731319 :8F180D34:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:39.188111 :8F180F70:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:39.173 2025-04-26 17:10:39.188113 :8F180F71:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10668 pso_num=25 pso_serial#=109 2025-04-26 17:10:39.188248 :8F180F72:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=10668 (legacy spawn) 2025-04-26 17:10:39.194655 :8F180F73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:10:39.194826 :8F180F78:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:10:39.195639 :8F180F7D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:39.204187 :8F180F80:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:39.189 2025-04-26 17:10:39.204189 :8F180F81:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9120 pso_num=25 pso_serial#=110 2025-04-26 17:10:39.204318 :8F180F82:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9120 (legacy spawn) 2025-04-26 17:10:39.210814 :8F180F83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:10:39.210982 :8F180F8B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:10:39.211841 :8F180F8D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:42.404911 :8F181090:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:42.402 2025-04-26 17:10:42.404913 :8F181091:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9780 pso_num=25 pso_serial#=111 2025-04-26 17:10:42.405047 :8F181092:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9780 (legacy spawn) 2025-04-26 17:10:42.411466 :8F181093:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:10:42.411638 :8F181098:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:10:42.412453 :8F18109D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:42.421014 :8F1810C0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:42.418 2025-04-26 17:10:42.421017 :8F1810C1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11236 pso_num=25 pso_serial#=112 2025-04-26 17:10:42.421151 :8F1810C2:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11236 (legacy spawn) 2025-04-26 17:10:42.427585 :8F1810C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:10:42.427750 :8F1810CC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:10:42.428560 :8F1810CD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:43.238137 :8F181118:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:43.230 2025-04-26 17:10:43.238139 :8F181119:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11056 pso_num=25 pso_serial#=113 2025-04-26 17:10:43.238288 :8F18111A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11056 (legacy spawn) 2025-04-26 17:10:43.244686 :8F18111B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:10:43.244874 :8F181124:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:10:43.245679 :8F181125:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:10:53.791082 :8F18148A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:10:53.786 2025-04-26 17:10:53.791084 :8F18148B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9488 pso_num=25 pso_serial#=114 2025-04-26 17:10:53.791218 :8F18148C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9488 osp_idx=23 osp_ver=65393 osp_pg=0 (spawn #120922) 2025-04-26 17:10:53.791218 :8F18148D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 17:10:53.791219 :8F18148E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=2958255593 2025-04-26 17:10:53.791219 :8F18148F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 17:10:53.791220 :8F181490:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 17:10:53.791220 :8F181491:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 17:10:53.791221 :8F181492:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 17:10:53.791221 :8F181493:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 17:10:53.791718 :8F181494:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 17:10:53.791764 :8F18149C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 17:20:54.731002 :8F18E196:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:20:55.173190 :8F18E1D0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:20:55.168 2025-04-26 17:20:55.173192 :8F18E1D1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9480 pso_num=25 pso_serial#=115 2025-04-26 17:20:55.173320 :8F18E1D2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9480 osp_idx=23 osp_ver=65394 osp_pg=0 (spawn #120944) 2025-04-26 17:20:55.173320 :8F18E1D3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 17:20:55.173321 :8F18E1D4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=2958856968 2025-04-26 17:20:55.173321 :8F18E1D5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 17:20:55.173321 :8F18E1D6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 17:20:55.173322 :8F18E1D7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 17:20:55.173322 :8F18E1D8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 17:20:55.173322 :8F18E1D9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 17:20:55.173799 :8F18E1DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 17:20:55.173847 :8F18E1E1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 17:30:55.918237 :8F19ABF5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:30:56.160560 :8F19ABF8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:30:56.153 2025-04-26 17:30:56.160562 :8F19ABF9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9340 pso_num=25 pso_serial#=116 2025-04-26 17:30:56.160697 :8F19ABFA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=9340 (legacy spawn) 2025-04-26 17:30:56.167125 :8F19ABFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-26 17:30:56.167289 :8F19AC00:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-04-26 17:30:56.168079 :8F19AC10:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:30:56.407450 :8F19AC35:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:30:56.403 2025-04-26 17:30:56.407452 :8F19AC36:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7192 pso_num=25 pso_serial#=117 2025-04-26 17:30:56.407581 :8F19AC37:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7192 osp_idx=23 osp_ver=65396 osp_pg=0 (spawn #120967) 2025-04-26 17:30:56.407582 :8F19AC38:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 17:30:56.407582 :8F19AC39:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=17 time=2959458203 2025-04-26 17:30:56.407583 :8F19AC3A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 17:30:56.407584 :8F19AC3B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 17:30:56.407584 :8F19AC3C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 17:30:56.407584 :8F19AC3D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 17:30:56.407585 :8F19AC3E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 17:30:56.408085 :8F19AC3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 17:30:56.408133 :8F19AC47:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 17:32:42.346540 :8F19CFA2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 17:40:57.724216 :8F1A76D8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-26 17:50:58.591254 :8F1B40EB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 17:50:59.127193 :8F1B4133:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 17:50:59.123 2025-04-26 17:50:59.127195 :8F1B4134:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10572 pso_num=25 pso_serial#=118 2025-04-26 17:50:59.127341 :8F1B4135:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10572 osp_idx=23 osp_ver=65397 osp_pg=0 (spawn #121012) 2025-04-26 17:50:59.127342 :8F1B4136:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 17:50:59.127343 :8F1B4137:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=18 time=2960660921 2025-04-26 17:50:59.127343 :8F1B4138:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 17:50:59.127343 :8F1B4139:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 17:50:59.127344 :8F1B413A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 17:50:59.127344 :8F1B413B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 17:50:59.127344 :8F1B413C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 17:50:59.127848 :8F1B413D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 17:50:59.127897 :8F1B4147:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 18:01:00.016790 :8F1C0E41:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-26 18:01:00.484618 :8F1C0E70:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-26 18:01:00.479 2025-04-26 18:01:00.484620 :8F1C0E71:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11140 pso_num=25 pso_serial#=119 2025-04-26 18:01:00.484752 :8F1C0E72:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11140 osp_idx=23 osp_ver=65398 osp_pg=0 (spawn #121037) 2025-04-26 18:01:00.484752 :8F1C0E73:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 18:01:00.484753 :8F1C0E74:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=19 time=2961262281 2025-04-26 18:01:00.484753 :8F1C0E75:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 18:01:00.484754 :8F1C0E76:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 18:01:00.484754 :8F1C0E77:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 18:01:00.484754 :8F1C0E78:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 18:01:00.484755 :8F1C0E79:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 18:01:00.485237 :8F1C0E7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-26 18:01:00.485282 :8F1C0E80:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 11140, W000)