Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622100841\orcl_j002_13380_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:36389M/63366M, Ph+PgF:42669M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 13380, image: ORACLE.EXE (J002) *** 2025-06-22 10:08:41.366 *** SESSION ID:(219.55293) 2025-06-22 10:08:41.366 *** 2025-06-22 10:08:41.366 Process diagnostic dump for ORACLE.EXE (J002), OS id=13380, pid: 27, proc_ser: 31, sid: 219, sess_ser: 55293 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13380 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.199643 sec, exc=0.199643 sec, total=0.199643 sec wait times: max=0.500000 sec, heur=6.213766 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510105 sec, exc=0.510105 sec, total=0.510105 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499979 sec, exc=0.499979 sec, total=0.499979 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500012 sec, exc=0.500012 sec, total=0.500012 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500011 sec, exc=0.500011 sec, total=0.500011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time Sampled Session History of session 219 serial 55293 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 10:08:35 - 10:08:41] idle wait at each sample [session created at: 10:08:35] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-22 10:08:41.366 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 13380, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-22 10:05:26.641733 :A80EED4F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18124 (legacy spawn) 2025-06-22 10:05:26.648255 :A80EED50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:05:26.648425 :A80EED55:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:05:26.649241 :A80EED5A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:00.752733 :A80EFDEB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:00.743 2025-06-22 10:06:00.752735 :A80EFDEC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9288 pso_num=27 pso_serial#=10 2025-06-22 10:06:00.752874 :A80EFDED:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9288 (legacy spawn) 2025-06-22 10:06:00.759319 :A80EFDEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:00.759480 :A80EFDF3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:00.760297 :A80EFDF8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:08.324217 :A80F01A3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:08.321 2025-06-22 10:06:08.324219 :A80F01A4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15264 pso_num=27 pso_serial#=11 2025-06-22 10:06:08.324354 :A80F01A5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15264 (legacy spawn) 2025-06-22 10:06:08.330848 :A80F01A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:08.331020 :A80F01AC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:08.331840 :A80F01B0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:08.359956 :A80F01B3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:08.352 2025-06-22 10:06:08.359958 :A80F01B4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18020 pso_num=27 pso_serial#=12 2025-06-22 10:06:08.360093 :A80F01B5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18020 (legacy spawn) 2025-06-22 10:06:08.366528 :A80F01B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:08.366690 :A80F01BF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:08.367479 :A80F01C0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:10.189712 :A80F023A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:10.181 2025-06-22 10:06:10.189715 :A80F023B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17560 pso_num=27 pso_serial#=13 2025-06-22 10:06:10.189890 :A80F023C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17560 (legacy spawn) 2025-06-22 10:06:10.197067 :A80F023D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:10.197266 :A80F0242:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:10.198245 :A80F0247:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:11.697894 :A80F02C4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:11.685 2025-06-22 10:06:11.697897 :A80F02C5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18204 pso_num=27 pso_serial#=14 2025-06-22 10:06:11.698029 :A80F02C6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18204 (legacy spawn) 2025-06-22 10:06:11.704462 :A80F02C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:11.704628 :A80F02D0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:11.705403 :A80F02D1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:11.714073 :A80F02D4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:11.701 2025-06-22 10:06:11.714075 :A80F02D5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14372 pso_num=27 pso_serial#=15 2025-06-22 10:06:11.714205 :A80F02D6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14372 (legacy spawn) 2025-06-22 10:06:11.720618 :A80F02D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:11.720785 :A80F02E0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:11.721599 :A80F02E1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:13.197903 :A80F035C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:13.185 2025-06-22 10:06:13.197905 :A80F035D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12136 pso_num=27 pso_serial#=16 2025-06-22 10:06:13.198100 :A80F035E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12136 (legacy spawn) 2025-06-22 10:06:13.557844 :A80F0372:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:13.558026 :A80F037B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:13.558885 :A80F037C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:06:13.567862 :A80F037F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:06:13.560 2025-06-22 10:06:13.567863 :A80F0380:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15092 pso_num=27 pso_serial#=17 2025-06-22 10:06:13.568002 :A80F0381:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15092 (legacy spawn) 2025-06-22 10:06:13.574557 :A80F0382:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:06:13.574725 :A80F038B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:06:13.575556 :A80F03B0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:09.142283 :A80F1544:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:09.129 2025-06-22 10:07:09.142286 :A80F1545:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15152 pso_num=27 pso_serial#=18 2025-06-22 10:07:09.142428 :A80F1546:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15152 (legacy spawn) 2025-06-22 10:07:09.148881 :A80F1547:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:09.149058 :A80F154C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:09.149858 :A80F1551:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:12.411719 :A80F1643:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:12.403 2025-06-22 10:07:12.411722 :A80F1644:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4696 pso_num=27 pso_serial#=19 2025-06-22 10:07:12.411883 :A80F1645:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4696 (legacy spawn) 2025-06-22 10:07:12.418370 :A80F1646:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:12.418545 :A80F164B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:12.419562 :A80F1650:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:21.297536 :A80F1907:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:21.282 2025-06-22 10:07:21.297538 :A80F1908:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17756 pso_num=27 pso_serial#=20 2025-06-22 10:07:21.297677 :A80F1909:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17756 (legacy spawn) 2025-06-22 10:07:21.304310 :A80F190A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:21.304475 :A80F1910:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:21.305297 :A80F1914:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:21.314316 :A80F1917:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:21.313 2025-06-22 10:07:21.314318 :A80F1918:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16428 pso_num=27 pso_serial#=21 2025-06-22 10:07:21.314465 :A80F1919:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16428 (legacy spawn) 2025-06-22 10:07:21.321126 :A80F191A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:21.321293 :A80F1923:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:21.322109 :A80F1924:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:24.498764 :A80F1A18:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:24.485 2025-06-22 10:07:24.498766 :A80F1A19:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8880 pso_num=27 pso_serial#=22 2025-06-22 10:07:24.498919 :A80F1A1A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8880 (legacy spawn) 2025-06-22 10:07:24.505767 :A80F1A1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:24.505968 :A80F1A20:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:24.506819 :A80F1A25:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:24.516293 :A80F1A28:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:24.516 2025-06-22 10:07:24.516295 :A80F1A29:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14012 pso_num=27 pso_serial#=23 2025-06-22 10:07:24.516446 :A80F1A2A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14012 (legacy spawn) 2025-06-22 10:07:24.523362 :A80F1A2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:24.523554 :A80F1A34:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:24.524434 :A80F1A35:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:57.613786 :A80F24F7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:57.599 2025-06-22 10:07:57.613788 :A80F24F8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17916 pso_num=27 pso_serial#=24 2025-06-22 10:07:57.613930 :A80F24F9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17916 (legacy spawn) 2025-06-22 10:07:57.620339 :A80F24FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:57.620511 :A80F24FF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:57.621339 :A80F2504:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:07:57.629919 :A80F2507:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:07:57.615 2025-06-22 10:07:57.629921 :A80F2508:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16772 pso_num=27 pso_serial#=25 2025-06-22 10:07:57.630059 :A80F2509:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16772 (legacy spawn) 2025-06-22 10:07:57.636489 :A80F250A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:07:57.636657 :A80F2513:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:07:57.637438 :A80F2514:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:08:00.707397 :A80F25F7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:08:00.694 2025-06-22 10:08:00.707399 :A80F25F8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14488 pso_num=27 pso_serial#=26 2025-06-22 10:08:00.707531 :A80F25F9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14488 (legacy spawn) 2025-06-22 10:08:00.714000 :A80F25FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:00.714169 :A80F25FF:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:08:00.714996 :A80F2604:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:08:00.723630 :A80F2607:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:08:00.710 2025-06-22 10:08:00.723632 :A80F2608:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16692 pso_num=27 pso_serial#=27 2025-06-22 10:08:00.723773 :A80F2609:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16692 (legacy spawn) 2025-06-22 10:08:00.730225 :A80F260A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:00.730404 :A80F2613:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:08:00.731181 :A80F2614:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:08:14.584712 :A80F2AA5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:08:14.575 2025-06-22 10:08:14.584715 :A80F2AA6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18060 pso_num=27 pso_serial#=28 2025-06-22 10:08:14.584845 :A80F2AA7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18060 (legacy spawn) 2025-06-22 10:08:14.591353 :A80F2AA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:14.591525 :A80F2AAD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:08:14.592335 :A80F2AB2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:08:17.250598 :A80F2B93:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:08:17.242 2025-06-22 10:08:17.250601 :A80F2B94:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14720 pso_num=27 pso_serial#=29 2025-06-22 10:08:17.250737 :A80F2B95:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14720 (legacy spawn) 2025-06-22 10:08:17.257165 :A80F2B96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:17.257336 :A80F2B9F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06-22 10:08:17.258357 :A80F2BA0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:08:34.959345 :A80F319C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:08:34.955 2025-06-22 10:08:34.959346 :A80F319D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15676 pso_num=27 pso_serial#=30 2025-06-22 10:08:34.959492 :A80F319E:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15676 osp_idx=25 osp_ver=93104 osp_pg=0 (spawn #310861) 2025-06-22 10:08:34.959494 :A80F319F:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:08:34.959494 :A80F31A0:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=243 time=3562658141 2025-06-22 10:08:34.959495 :A80F31A1:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:08:34.959495 :A80F31A2:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:08:34.959495 :A80F31A3:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:08:34.959496 :A80F31A4:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:08:34.959496 :A80F31A5:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:08:34.960034 :A80F31A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-22 10:08:34.960081 :A80F31AD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-22 10:08:34.960153 :A80F31B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-22 10:08:34.964697 :A80F31D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.017209 :A80F31DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.019421 :A80F31E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.021075 :A80F31EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.021607 :A80F31F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.021627 :A80F31F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.021665 :A80F3202:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-22 10:08:35.022646 :A80F320D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.022664 :A80F3210:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.022696 :A80F321B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-22 10:08:35.023238 :A80F3226:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.025588 :A80F322E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.030675 :A80F3236:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.031973 :A80F323E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.036820 :A80F3246:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.038092 :A80F324E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.041025 :A80F3256:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.042079 :A80F325E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.042086 :A80F325F:db_trace:ktur.c@3079:ktuabt(): [10444:27:219] ABORT TRANSACTION - xid: 0x000a.006.0016f3da 2025-06-22 10:08:35.044936 :A80F3267:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.045098 :A80F3276:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.045297 :A80F3281:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.045344 :A80F3284:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-06-22 10:08:35.045357 :A80F3285:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-06-22 10:08:35.047188 :A80F329B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.047376 :A80F329D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.047413 :A80F32A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-06-22 10:08:35.047424 :A80F32A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-06-22 10:08:35.047610 :A80F32A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.047625 :A80F32AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-22 10:08:35.047662 :A80F32B5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-22 10:08:35.049663 :A80F32C0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-22 10:08:35.164174 :A80F33BB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-22 10:08:35.159 2025-06-22 10:08:35.164176 :A80F33BC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13380 pso_num=27 pso_serial#=31 2025-06-22 10:08:35.164325 :A80F33BD:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13380 osp_idx=25 osp_ver=93105 osp_pg=0 (spawn #310864) 2025-06-22 10:08:35.164326 :A80F33BE:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-22 10:08:35.164326 :A80F33BF:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=244 time=3562658360 2025-06-22 10:08:35.164327 :A80F33C0:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 10:08:35.164327 :A80F33C1:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 10:08:35.164328 :A80F33C2:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 10:08:35.164328 :A80F33C3:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 10:08:35.164328 :A80F33C4:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 10:08:35.164818 :A80F33CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 13380, J002)