Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719140244\orcl_j002_4696_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:36177M/63366M, Ph+PgF:42089M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 4696, image: ORACLE.EXE (J002) *** 2025-07-19 14:02:45.044 *** SESSION ID:(219.19753) 2025-07-19 14:02:45.044 *** 2025-07-19 14:02:45.044 Process diagnostic dump for ORACLE.EXE (J002), OS id=4696, pid: 27, proc_ser: 196, sid: 219, sess_ser: 19753 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4696 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.123521 sec, exc=0.123521 sec, total=0.123521 sec wait times: max=0.500000 sec, heur=6.389888 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.000014 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.502054 sec, exc=0.502054 sec, total=0.502054 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499231 sec, exc=0.499231 sec, total=0.499231 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.266393 sec, exc=0.266393 sec, total=0.266393 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 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=6 seq_num=7 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.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.505084 sec, exc=0.505084 sec, total=0.505084 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time Sampled Session History of session 219 serial 19753 --------------------------------------------------- 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, 14:02:38 - 14:02:45] idle wait at each sample [session created at: 14:02:38] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-19 14:02:45.044 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 4696, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-19 13:58:11.216588 :B421C2DF: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-07-19 13:58:11.216758 :B421C2E8: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-07-19 13:58:11.217567 :B421C2E9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:11.226295 :B421C2EC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:11.217 2025-07-19 13:58:11.226298 :B421C2ED:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17540 pso_num=27 pso_serial#=169 2025-07-19 13:58:11.226430 :B421C2EE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17540 (legacy spawn) 2025-07-19 13:58:11.232903 :B421C2EF: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-07-19 13:58:11.233063 :B421C2F8: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-07-19 13:58:11.233910 :B421C2F9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:44.301788 :B421CDEC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:44.287 2025-07-19 13:58:44.301790 :B421CDED:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19204 pso_num=27 pso_serial#=170 2025-07-19 13:58:44.301929 :B421CDEE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19204 (legacy spawn) 2025-07-19 13:58:44.308332 :B421CDEF: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-07-19 13:58:44.308500 :B421CDF8: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-07-19 13:58:44.309284 :B421CDF9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:44.326130 :B421CDFC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:44.319 2025-07-19 13:58:44.326133 :B421CDFD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18440 pso_num=27 pso_serial#=171 2025-07-19 13:58:44.326266 :B421CDFE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18440 (legacy spawn) 2025-07-19 13:58:44.332724 :B421CDFF: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-07-19 13:58:44.332888 :B421CE08: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-07-19 13:58:44.333716 :B421CE09:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:47.502286 :B421CF18:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:47.489 2025-07-19 13:58:47.502288 :B421CF19:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4288 pso_num=27 pso_serial#=172 2025-07-19 13:58:47.502422 :B421CF1A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4288 (legacy spawn) 2025-07-19 13:58:47.508919 :B421CF1B: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-07-19 13:58:47.509106 :B421CF24: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-07-19 13:58:47.509963 :B421CF25:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:47.518639 :B421CF28:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:47.504 2025-07-19 13:58:47.518641 :B421CF29:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18456 pso_num=27 pso_serial#=173 2025-07-19 13:58:47.518776 :B421CF2A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18456 (legacy spawn) 2025-07-19 13:58:47.525283 :B421CF2B: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-07-19 13:58:47.525448 :B421CF34: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-07-19 13:58:47.526248 :B421CF35:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:55.469421 :B421D1A6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:55.461 2025-07-19 13:58:55.469423 :B421D1A7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10208 pso_num=27 pso_serial#=174 2025-07-19 13:58:55.469557 :B421D1A8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10208 (legacy spawn) 2025-07-19 13:58:55.475999 :B421D1A9: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-07-19 13:58:55.476174 :B421D1AE: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-07-19 13:58:55.476984 :B421D1B3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:59.103824 :B421D31E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:59.101 2025-07-19 13:58:59.103826 :B421D31F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19236 pso_num=27 pso_serial#=175 2025-07-19 13:58:59.103965 :B421D320:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19236 (legacy spawn) 2025-07-19 13:58:59.110383 :B421D321: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-07-19 13:58:59.110565 :B421D32A: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-07-19 13:58:59.111357 :B421D32B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:58:59.126293 :B421D32E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:58:59.117 2025-07-19 13:58:59.126295 :B421D32F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17868 pso_num=27 pso_serial#=176 2025-07-19 13:58:59.126437 :B421D330:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17868 (legacy spawn) 2025-07-19 13:58:59.132997 :B421D331: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-07-19 13:58:59.133164 :B421D338: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-07-19 13:58:59.133971 :B421D33A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:59:06.090931 :B421D53A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:59:06.083 2025-07-19 13:59:06.090933 :B421D53B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18108 pso_num=27 pso_serial#=177 2025-07-19 13:59:06.091069 :B421D53C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18108 (legacy spawn) 2025-07-19 13:59:06.097521 :B421D53D: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-07-19 13:59:06.097697 :B421D546: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-07-19 13:59:06.098519 :B421D547:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:59:57.036812 :B421E6CA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:59:57.034 2025-07-19 13:59:57.036814 :B421E6CB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15772 pso_num=27 pso_serial#=178 2025-07-19 13:59:57.036950 :B421E6CC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15772 (legacy spawn) 2025-07-19 13:59:57.043511 :B421E6CD: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-07-19 13:59:57.043687 :B421E6D6: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-07-19 13:59:57.044510 :B421E6D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 13:59:57.053289 :B421E6DA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 13:59:57.049 2025-07-19 13:59:57.053291 :B421E6DB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17624 pso_num=27 pso_serial#=179 2025-07-19 13:59:57.053433 :B421E6DC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17624 (legacy spawn) 2025-07-19 13:59:57.059940 :B421E6DD: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-07-19 13:59:57.060106 :B421E6E6: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-07-19 13:59:57.060923 :B421E6E7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:00:00.213898 :B421E7E0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:00:00.200 2025-07-19 14:00:00.213900 :B421E7E1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15216 pso_num=27 pso_serial#=180 2025-07-19 14:00:00.214038 :B421E7E2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15216 (legacy spawn) 2025-07-19 14:00:00.220553 :B421E7E3: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-07-19 14:00:00.220734 :B421E7EC: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-07-19 14:00:00.221532 :B421E7ED:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:00:00.240761 :B421E7F0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:00:00.231 2025-07-19 14:00:00.240763 :B421E7F1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11700 pso_num=27 pso_serial#=181 2025-07-19 14:00:00.240904 :B421E7F2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11700 (legacy spawn) 2025-07-19 14:00:00.247480 :B421E7F3: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-07-19 14:00:00.247637 :B421E7F8: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-07-19 14:00:00.248455 :B421E7FD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:00:03.662308 :B421E8F7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:00:03.653 2025-07-19 14:00:03.662310 :B421E8F8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12684 pso_num=27 pso_serial#=182 2025-07-19 14:00:03.662450 :B421E8F9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12684 (legacy spawn) 2025-07-19 14:00:03.668937 :B421E8FA: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-07-19 14:00:03.669112 :B421E8FF: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-07-19 14:00:03.669942 :B421E904:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:00:08.299808 :B421EAD2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:00:08.291 2025-07-19 14:00:08.299810 :B421EAD3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17004 pso_num=27 pso_serial#=183 2025-07-19 14:00:08.299980 :B421EAD4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17004 (legacy spawn) 2025-07-19 14:00:08.307118 :B421EAD5: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-07-19 14:00:08.307361 :B421EADB: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-07-19 14:00:08.308378 :B421EADF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:09.715932 :B42200B9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:09.712 2025-07-19 14:01:09.715935 :B42200BA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2796 pso_num=27 pso_serial#=184 2025-07-19 14:01:09.716070 :B42200BB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2796 (legacy spawn) 2025-07-19 14:01:09.722539 :B42200BC: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-07-19 14:01:09.722721 :B42200C1: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-07-19 14:01:09.723490 :B42200C6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:09.732229 :B42200C9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:09.728 2025-07-19 14:01:09.732231 :B42200CA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13728 pso_num=27 pso_serial#=185 2025-07-19 14:01:09.732367 :B42200CB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13728 (legacy spawn) 2025-07-19 14:01:09.738817 :B42200CC: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-07-19 14:01:09.738990 :B42200D5: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-07-19 14:01:09.739791 :B42200D6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:10.548872 :B4220131:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:10.541 2025-07-19 14:01:10.548874 :B4220132:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18488 pso_num=27 pso_serial#=186 2025-07-19 14:01:10.549012 :B4220133:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18488 (legacy spawn) 2025-07-19 14:01:10.555579 :B4220134: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-07-19 14:01:10.555766 :B422013C: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-07-19 14:01:10.556596 :B422013E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:11.846531 :B42201BB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:11.838 2025-07-19 14:01:11.846533 :B42201BC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17424 pso_num=27 pso_serial#=187 2025-07-19 14:01:11.846665 :B42201BD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17424 (legacy spawn) 2025-07-19 14:01:11.853194 :B42201BE: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-07-19 14:01:11.853389 :B42201C3: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-07-19 14:01:11.854181 :B42201C8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:12.897737 :B42201E2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:12.885 2025-07-19 14:01:12.897739 :B42201E3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15432 pso_num=27 pso_serial#=188 2025-07-19 14:01:12.897878 :B42201E4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15432 (legacy spawn) 2025-07-19 14:01:12.904372 :B42201E5: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-07-19 14:01:12.904540 :B42201EE: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-07-19 14:01:12.905350 :B42201EF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:12.914070 :B42201F2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:12.900 2025-07-19 14:01:12.914072 :B42201F3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4764 pso_num=27 pso_serial#=189 2025-07-19 14:01:12.914260 :B42201F4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4764 (legacy spawn) 2025-07-19 14:01:12.920664 :B42201F5: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-07-19 14:01:12.920845 :B42201FD: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-07-19 14:01:12.921667 :B42201FF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:45.929806 :B4220CD7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:45.919 2025-07-19 14:01:45.929808 :B4220CD8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16332 pso_num=27 pso_serial#=190 2025-07-19 14:01:45.929961 :B4220CD9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16332 (legacy spawn) 2025-07-19 14:01:45.937061 :B4220CDA: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-07-19 14:01:45.937285 :B4220CDF: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-07-19 14:01:45.938161 :B4220CE4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:45.960704 :B4220CE7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:45.950 2025-07-19 14:01:45.960706 :B4220CE8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19116 pso_num=27 pso_serial#=191 2025-07-19 14:01:45.960857 :B4220CE9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19116 (legacy spawn) 2025-07-19 14:01:45.968102 :B4220CEA: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-07-19 14:01:45.968281 :B4220CF3: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-07-19 14:01:45.969218 :B4220CF4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:45.978316 :B4220CF7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:45.966 2025-07-19 14:01:45.978318 :B4220CF8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14016 pso_num=27 pso_serial#=192 2025-07-19 14:01:45.978465 :B4220CF9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14016 (legacy spawn) 2025-07-19 14:01:45.985392 :B4220CFA: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-07-19 14:01:45.985569 :B4220D03: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-07-19 14:01:45.986465 :B4220D04:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:49.031522 :B4220E02:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:49.018 2025-07-19 14:01:49.031524 :B4220E03:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15304 pso_num=27 pso_serial#=193 2025-07-19 14:01:49.031667 :B4220E04:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15304 (legacy spawn) 2025-07-19 14:01:49.038239 :B4220E05: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-07-19 14:01:49.038421 :B4220E0A: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-07-19 14:01:49.039230 :B4220E0F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:01:49.047901 :B4220E12:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:01:49.033 2025-07-19 14:01:49.047903 :B4220E13:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15516 pso_num=27 pso_serial#=194 2025-07-19 14:01:49.048035 :B4220E14:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15516 (legacy spawn) 2025-07-19 14:01:49.069402 :B4220E15: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-07-19 14:01:49.069576 :B4220E1E: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-07-19 14:01:49.070389 :B4220E1F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:02:12.753081 :B42215A4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:02:12.740 2025-07-19 14:02:12.753083 :B42215A5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8928 pso_num=27 pso_serial#=195 2025-07-19 14:02:12.753218 :B42215A6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8928 (legacy spawn) 2025-07-19 14:02:12.759650 :B42215A7: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-07-19 14:02:12.759819 :B42215AC: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-07-19 14:02:12.760619 :B42215B1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-19 14:02:38.667592 :B4222310:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-19 14:02:38.662 2025-07-19 14:02:38.667594 :B4222311:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4696 pso_num=27 pso_serial#=196 2025-07-19 14:02:38.667742 :B4222312:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=4696 osp_idx=25 osp_ver=173604 osp_pg=0 (spawn #401403) 2025-07-19 14:02:38.667743 :B4222313:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 14:02:38.667744 :B4222314:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=118 time=1614534564 2025-07-19 14:02:38.667745 :B4222315:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 14:02:38.667745 :B4222316:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 14:02:38.667745 :B4222317:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 14:02:38.667746 :B4222318:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 14:02:38.667747 :B4222319:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 14:02:38.668259 :B4222323: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 2025-07-19 14:02:43.433468 :B42226A7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 4696, J002)