Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816220848\orcl_j002_21860_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:35443M/63366M, Ph+PgF:40773M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 21860, image: ORACLE.EXE (J002) *** 2025-08-16 22:08:48.183 *** SESSION ID:(225.12363) 2025-08-16 22:08:48.183 *** 2025-08-16 22:08:48.183 Process diagnostic dump for ORACLE.EXE (J002), OS id=21860, pid: 28, proc_ser: 224, sid: 225, sess_ser: 12363 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21860 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.376233 sec, exc=0.376233 sec, total=0.376233 sec wait times: max=0.500000 sec, heur=5.876648 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.505092 sec, exc=0.505092 sec, total=0.505092 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.502093 sec, exc=0.502093 sec, total=0.502093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 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=1 seq_num=2 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.000010 sec of elapsed time Sampled Session History of session 225 serial 12363 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:08:42 - 22:08:48] idle wait at each sample [session created at: 22:08:42] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 22:08:48.183 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 21860, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 22:06:03.578816 :C0AB11D1:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:06:03.578816 :C0AB11D2:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:06:03.578817 :C0AB11D3:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:06:03.578817 :C0AB11D4:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:06:03.578817 :C0AB11D5:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:06:03.580537 :C0AB11D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:06:03.580591 :C0AB11DC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-08-16 22:06:03.581034 :C0AB11DD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:05.943634 :C0AB136D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:05.937 2025-08-16 22:06:05.943636 :C0AB136E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22464 pso_num=28 pso_serial#=202 2025-08-16 22:06:05.943776 :C0AB136F:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22464 osp_idx=26 osp_ver=235360 osp_pg=0 (spawn #495835) 2025-08-16 22:06:05.943776 :C0AB1370:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:06:05.943777 :C0AB1371:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=167 time=4062635251 2025-08-16 22:06:05.943777 :C0AB1372:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:06:05.943778 :C0AB1373:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:06:05.943778 :C0AB1374:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:06:05.943778 :C0AB1375:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:06:05.943779 :C0AB1376:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:06:05.944300 :C0AB1377:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:06:05.944349 :C0AB137D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-08-16 22:06:05.944758 :C0AB137E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:06.980433 :C0AB15F7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:06.978 2025-08-16 22:06:06.980435 :C0AB15F8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22372 pso_num=28 pso_serial#=203 2025-08-16 22:06:06.980587 :C0AB15F9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22372 (legacy spawn) 2025-08-16 22:06:06.989498 :C0AB160F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:06.989946 :C0AB1617:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:06:06.991387 :C0AB1619:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:07.001429 :C0AB16C8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:06.994 2025-08-16 22:06:07.001431 :C0AB16C9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21136 pso_num=28 pso_serial#=204 2025-08-16 22:06:07.001571 :C0AB16CA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21136 (legacy spawn) 2025-08-16 22:06:07.008149 :C0AB16CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:07.008451 :C0AB16D3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:06:07.009289 :C0AB16D5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:43.231050 :C0AB31E0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:43.220 2025-08-16 22:06:43.231053 :C0AB31E1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19304 pso_num=28 pso_serial#=205 2025-08-16 22:06:43.231186 :C0AB31E2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19304 (legacy spawn) 2025-08-16 22:06:43.237684 :C0AB31F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:43.237846 :C0AB31FE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:06:43.238627 :C0AB3203:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:43.247235 :C0AB3206:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:43.235 2025-08-16 22:06:43.247237 :C0AB3207:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22356 pso_num=28 pso_serial#=206 2025-08-16 22:06:43.247371 :C0AB3208:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22356 (legacy spawn) 2025-08-16 22:06:43.253896 :C0AB3209:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:43.254062 :C0AB3212:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:06:43.254911 :C0AB3213:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:43.290981 :C0AB3216:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:43.282 2025-08-16 22:06:43.290983 :C0AB3217:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16996 pso_num=28 pso_serial#=207 2025-08-16 22:06:43.291123 :C0AB3218:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16996 (legacy spawn) 2025-08-16 22:06:43.297623 :C0AB3219:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:43.297796 :C0AB3221:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:06:43.298646 :C0AB3223:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:06:44.338815 :C0AB3278:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:06:44.329 2025-08-16 22:06:44.338817 :C0AB3279:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15328 pso_num=28 pso_serial#=208 2025-08-16 22:06:44.338962 :C0AB327A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15328 (legacy spawn) 2025-08-16 22:06:44.345481 :C0AB327B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:44.345651 :C0AB3283:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:06:44.346456 :C0AB3285:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:07:18.428685 :C0AB3DB6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:07:18.419 2025-08-16 22:07:18.428687 :C0AB3DB7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21144 pso_num=28 pso_serial#=209 2025-08-16 22:07:18.428823 :C0AB3DB8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21144 (legacy spawn) 2025-08-16 22:07:18.435295 :C0AB3DB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:07:18.435466 :C0AB3DC2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:07:18.436299 :C0AB3DC3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:07:19.533665 :C0AB3E22:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:07:19.526 2025-08-16 22:07:19.533667 :C0AB3E23:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22028 pso_num=28 pso_serial#=210 2025-08-16 22:07:19.533802 :C0AB3E24:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22028 (legacy spawn) 2025-08-16 22:07:19.540297 :C0AB3E25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:07:19.540459 :C0AB3E2A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:07:19.541262 :C0AB3E2F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:07:19.549928 :C0AB3E32:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:07:19.541 2025-08-16 22:07:19.549930 :C0AB3E33:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20392 pso_num=28 pso_serial#=211 2025-08-16 22:07:19.550076 :C0AB3E34:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20392 (legacy spawn) 2025-08-16 22:07:19.556638 :C0AB3E35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:07:19.556810 :C0AB3E3D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:07:19.557671 :C0AB3E3F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:07:22.774227 :C0AB3F38:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:07:22.761 2025-08-16 22:07:22.774229 :C0AB3F39:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21416 pso_num=28 pso_serial#=212 2025-08-16 22:07:22.774372 :C0AB3F3A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21416 (legacy spawn) 2025-08-16 22:07:22.781095 :C0AB3F3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:07:22.793302 :C0AB3F40:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:07:22.794225 :C0AB3F45:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:07:22.803061 :C0AB3F48:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:07:22.793 2025-08-16 22:07:22.803063 :C0AB3F49:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22240 pso_num=28 pso_serial#=213 2025-08-16 22:07:22.803199 :C0AB3F4A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22240 (legacy spawn) 2025-08-16 22:07:22.809711 :C0AB3F4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:07:22.809883 :C0AB3F53:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:07:22.810700 :C0AB3F55:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:07:45.469569 :C0AB4676:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:07:45.462 2025-08-16 22:07:45.469571 :C0AB4677:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21656 pso_num=28 pso_serial#=214 2025-08-16 22:07:45.469710 :C0AB4678:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21656 (legacy spawn) 2025-08-16 22:07:45.476338 :C0AB4679:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:07:45.476510 :C0AB467E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:07:45.477490 :C0AB4683:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:16.554451 :C0AB5103:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:16.541 2025-08-16 22:08:16.554453 :C0AB5104:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21124 pso_num=28 pso_serial#=215 2025-08-16 22:08:16.554591 :C0AB5105:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21124 (legacy spawn) 2025-08-16 22:08:16.561059 :C0AB5106:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:16.561227 :C0AB510B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:16.562037 :C0AB5110:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:18.095988 :C0AB519D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:18.083 2025-08-16 22:08:18.095990 :C0AB519E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19960 pso_num=28 pso_serial#=216 2025-08-16 22:08:18.096139 :C0AB519F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19960 (legacy spawn) 2025-08-16 22:08:18.102903 :C0AB51A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:18.103089 :C0AB51A8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:18.103922 :C0AB51AA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:18.122097 :C0AB51AD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:18.114 2025-08-16 22:08:18.122100 :C0AB51AE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19800 pso_num=28 pso_serial#=217 2025-08-16 22:08:18.122235 :C0AB51AF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19800 (legacy spawn) 2025-08-16 22:08:18.129139 :C0AB51B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:18.129317 :C0AB51B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:18.130336 :C0AB51BA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:28.723033 :C0AB5500:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:28.714 2025-08-16 22:08:28.723035 :C0AB5501:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2648 pso_num=28 pso_serial#=218 2025-08-16 22:08:28.723203 :C0AB5502:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2648 (legacy spawn) 2025-08-16 22:08:28.729688 :C0AB5503:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:28.729859 :C0AB5508:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:28.730712 :C0AB550D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:32.281615 :C0AB562F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:32.273 2025-08-16 22:08:32.281617 :C0AB5630:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22024 pso_num=28 pso_serial#=219 2025-08-16 22:08:32.281751 :C0AB5631:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22024 (legacy spawn) 2025-08-16 22:08:32.288184 :C0AB5632:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:32.288356 :C0AB5637:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:32.289183 :C0AB563C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:32.297883 :C0AB563F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:32.288 2025-08-16 22:08:32.297885 :C0AB5640:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22468 pso_num=28 pso_serial#=220 2025-08-16 22:08:32.298023 :C0AB5641:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22468 (legacy spawn) 2025-08-16 22:08:32.304542 :C0AB5642:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:32.304701 :C0AB564B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:32.305509 :C0AB564C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:35.381391 :C0AB5752:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:35.367 2025-08-16 22:08:35.381394 :C0AB5753:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19848 pso_num=28 pso_serial#=221 2025-08-16 22:08:35.381558 :C0AB5754:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19848 (legacy spawn) 2025-08-16 22:08:35.388771 :C0AB5755:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:35.389006 :C0AB575A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:35.389998 :C0AB575F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:35.399554 :C0AB5762:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:35.398 2025-08-16 22:08:35.399557 :C0AB5763:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21536 pso_num=28 pso_serial#=222 2025-08-16 22:08:35.399731 :C0AB5764:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21536 (legacy spawn) 2025-08-16 22:08:35.407020 :C0AB5765:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:35.407252 :C0AB576E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-16 22:08:35.408211 :C0AB576F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:42.108351 :C0AB59A3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:42.103 2025-08-16 22:08:42.108354 :C0AB59A4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21084 pso_num=28 pso_serial#=223 2025-08-16 22:08:42.108497 :C0AB59A5:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21084 osp_idx=26 osp_ver=235381 osp_pg=0 (spawn #495842) 2025-08-16 22:08:42.108498 :C0AB59A6:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:08:42.108499 :C0AB59A7:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=168 time=4062791408 2025-08-16 22:08:42.108499 :C0AB59A8:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:08:42.108499 :C0AB59A9:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:08:42.108500 :C0AB59AA:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:08:42.108500 :C0AB59AB:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:08:42.108501 :C0AB59AC:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:08:42.109012 :C0AB59AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:08:42.109062 :C0AB59B3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-08-16 22:08:42.113194 :C0AB59C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 22:08:42.113240 :C0AB59C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 22:08:42.113498 :C0AB59C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 22:08:42.113513 :C0AB59C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 22:08:42.113729 :C0AB59C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 22:08:42.113742 :C0AB59CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 22:08:42.113956 :C0AB59CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 22:08:42.113969 :C0AB59CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 22:08:42.114181 :C0AB59CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 22:08:42.114194 :C0AB59CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 22:08:42.114402 :C0AB59CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 22:08:42.114415 :C0AB59D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 22:08:42.116908 :C0AB59FE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 22:08:42.312200 :C0AB5FCE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 22:08:42.306 2025-08-16 22:08:42.312201 :C0AB5FCF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21860 pso_num=28 pso_serial#=224 2025-08-16 22:08:42.312324 :C0AB5FD0:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21860 osp_idx=26 osp_ver=235382 osp_pg=0 (spawn #495844) 2025-08-16 22:08:42.312325 :C0AB5FD1:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:08:42.312326 :C0AB5FD2:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=169 time=4062791611 2025-08-16 22:08:42.312326 :C0AB5FD3:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:08:42.312326 :C0AB5FD4:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:08:42.312327 :C0AB5FD5:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:08:42.312327 :C0AB5FD6:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:08:42.312328 :C0AB5FD7:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:08:42.312895 :C0AB5FE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 21860, J002)