Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250907180706\orcl_j002_23620_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:34965M/63366M, Ph+PgF:39777M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 23620, image: ORACLE.EXE (J002) *** 2025-09-07 18:07:06.868 *** SESSION ID:(225.18573) 2025-09-07 18:07:06.868 *** 2025-09-07 18:07:06.868 Process diagnostic dump for ORACLE.EXE (J002), OS id=23620, pid: 28, proc_ser: 222, sid: 225, sess_ser: 18573 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23620 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.051151 sec, exc=0.051151 sec, total=0.051151 sec wait times: max=0.500000 sec, heur=4.049566 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000022 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500041 sec, exc=0.500041 sec, total=0.500041 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500171 sec, exc=0.500171 sec, total=0.500171 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=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.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.504410 sec, exc=0.504410 sec, total=0.504410 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499983 sec, exc=0.499983 sec, total=0.499983 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=0 seq_num=1 snap_id=1 wait times: snap=0.493726 sec, exc=0.493726 sec, total=0.493726 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 225 serial 18573 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 18:07:02 - 18:07:06] idle wait at each sample [session created at: 18:07:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-07 18:07:06.868 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 23620, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-07 18:04:15.153162 :CA4D3592: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-09-07 18:04:15.153337 :CA4D359A: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-09-07 18:04:15.154133 :CA4D359C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:04:19.789490 :CA4D39F3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:04:19.781 2025-09-07 18:04:19.789492 :CA4D39F4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24316 pso_num=28 pso_serial#=201 2025-09-07 18:04:19.789630 :CA4D39F5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24316 (legacy spawn) 2025-09-07 18:04:19.796125 :CA4D39F6: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-09-07 18:04:19.796315 :CA4D39FB: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-09-07 18:04:19.797151 :CA4D3A00:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:04:23.501529 :CA4D3B10:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:04:23.486 2025-09-07 18:04:23.501531 :CA4D3B11:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21584 pso_num=28 pso_serial#=202 2025-09-07 18:04:23.501671 :CA4D3B12:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21584 (legacy spawn) 2025-09-07 18:04:23.508163 :CA4D3B13: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-09-07 18:04:23.508337 :CA4D3B18: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-09-07 18:04:23.509155 :CA4D3B1D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:04:23.517891 :CA4D3B20:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:04:23.502 2025-09-07 18:04:23.517900 :CA4D3B21:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20856 pso_num=28 pso_serial#=203 2025-09-07 18:04:23.518050 :CA4D3B22:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20856 (legacy spawn) 2025-09-07 18:04:23.524568 :CA4D3B23: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-09-07 18:04:23.524725 :CA4D3B2C: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-09-07 18:04:23.525536 :CA4D3B2D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:04:26.594445 :CA4D3C2A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:04:26.585 2025-09-07 18:04:26.594447 :CA4D3C2B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22372 pso_num=28 pso_serial#=204 2025-09-07 18:04:26.594602 :CA4D3C2C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22372 (legacy spawn) 2025-09-07 18:04:26.601481 :CA4D3C2D: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-09-07 18:04:26.601681 :CA4D3C36: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-09-07 18:04:26.602544 :CA4D3C37:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:04:26.611731 :CA4D3C3A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:04:26.601 2025-09-07 18:04:26.611733 :CA4D3C3B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21036 pso_num=28 pso_serial#=205 2025-09-07 18:04:26.611882 :CA4D3C3C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21036 (legacy spawn) 2025-09-07 18:04:26.619266 :CA4D3C3D: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-09-07 18:04:26.619453 :CA4D3C46: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-09-07 18:04:26.620540 :CA4D3C47:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:17.416339 :CA4D527A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:17.403 2025-09-07 18:05:17.416341 :CA4D527B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23216 pso_num=28 pso_serial#=206 2025-09-07 18:05:17.416482 :CA4D527C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23216 (legacy spawn) 2025-09-07 18:05:17.422956 :CA4D527D: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-09-07 18:05:17.422986 :CA4D5280: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-09-07 18:05:17.423147 :CA4D5283: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-09-07 18:05:17.423959 :CA4D5294:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:17.432637 :CA4D5297:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:17.419 2025-09-07 18:05:17.432639 :CA4D5298:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21912 pso_num=28 pso_serial#=207 2025-09-07 18:05:17.432773 :CA4D5299:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21912 (legacy spawn) 2025-09-07 18:05:17.439286 :CA4D529A: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-09-07 18:05:17.439449 :CA4D52A3: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-09-07 18:05:17.440271 :CA4D52A4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:21.989247 :CA4D53F9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:21.980 2025-09-07 18:05:21.989249 :CA4D53FA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11772 pso_num=28 pso_serial#=208 2025-09-07 18:05:21.989394 :CA4D53FB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11772 (legacy spawn) 2025-09-07 18:05:21.995921 :CA4D53FC: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-09-07 18:05:21.995952 :CA4D53FF: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-09-07 18:05:21.996111 :CA4D5406: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-09-07 18:05:21.996919 :CA4D5413:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:23.337910 :CA4D5473:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:23.329 2025-09-07 18:05:23.337912 :CA4D5474:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24532 pso_num=28 pso_serial#=209 2025-09-07 18:05:23.338044 :CA4D5475:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24532 (legacy spawn) 2025-09-07 18:05:23.344535 :CA4D5476: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-09-07 18:05:23.344703 :CA4D547B: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-09-07 18:05:23.345486 :CA4D5480:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:35.885312 :CA4D585E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:35.870 2025-09-07 18:05:35.885314 :CA4D585F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18472 pso_num=28 pso_serial#=210 2025-09-07 18:05:35.885450 :CA4D5860:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18472 (legacy spawn) 2025-09-07 18:05:35.891956 :CA4D5861: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-09-07 18:05:35.892144 :CA4D586A: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-09-07 18:05:35.892917 :CA4D586B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:35.901658 :CA4D586E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:35.886 2025-09-07 18:05:35.901659 :CA4D586F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22564 pso_num=28 pso_serial#=211 2025-09-07 18:05:35.901829 :CA4D5870:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22564 (legacy spawn) 2025-09-07 18:05:35.908344 :CA4D5871: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-09-07 18:05:35.908513 :CA4D587A: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-09-07 18:05:35.909316 :CA4D587B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:38.999947 :CA4D5969:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:38.985 2025-09-07 18:05:38.999949 :CA4D596A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24436 pso_num=28 pso_serial#=212 2025-09-07 18:05:39.000103 :CA4D596B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24436 (legacy spawn) 2025-09-07 18:05:39.007200 :CA4D596C: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-09-07 18:05:39.007380 :CA4D5975: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-09-07 18:05:39.008331 :CA4D5976:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:05:39.017683 :CA4D5979:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:05:39.011 2025-09-07 18:05:39.017686 :CA4D597A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20336 pso_num=28 pso_serial#=213 2025-09-07 18:05:39.017856 :CA4D597B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20336 (legacy spawn) 2025-09-07 18:05:39.024748 :CA4D597C: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-09-07 18:05:39.024940 :CA4D5985: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-09-07 18:05:39.025853 :CA4D5986:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:12.184642 :CA4D6474:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:12.170 2025-09-07 18:06:12.184645 :CA4D6475:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24160 pso_num=28 pso_serial#=214 2025-09-07 18:06:12.184788 :CA4D6476:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24160 (legacy spawn) 2025-09-07 18:06:12.191309 :CA4D6477: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-09-07 18:06:12.191477 :CA4D6480: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-09-07 18:06:12.192285 :CA4D6481:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:12.210655 :CA4D6484:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:12.202 2025-09-07 18:06:12.210657 :CA4D6485:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22492 pso_num=28 pso_serial#=215 2025-09-07 18:06:12.210804 :CA4D6486:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22492 (legacy spawn) 2025-09-07 18:06:12.217607 :CA4D6487: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-09-07 18:06:12.217794 :CA4D648F: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-09-07 18:06:12.218685 :CA4D6491:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:14.475159 :CA4D6517:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:14.467 2025-09-07 18:06:14.475162 :CA4D6518:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8240 pso_num=28 pso_serial#=216 2025-09-07 18:06:14.475304 :CA4D6519:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8240 (legacy spawn) 2025-09-07 18:06:14.481795 :CA4D651A: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-09-07 18:06:14.481973 :CA4D651F: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-09-07 18:06:14.482775 :CA4D6524:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:15.295566 :CA4D6582:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:15.282 2025-09-07 18:06:15.295568 :CA4D6583:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18812 pso_num=28 pso_serial#=217 2025-09-07 18:06:15.295708 :CA4D6584:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18812 (legacy spawn) 2025-09-07 18:06:15.302251 :CA4D6585: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-09-07 18:06:15.302418 :CA4D658E: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-09-07 18:06:15.303214 :CA4D658F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:15.312046 :CA4D6592:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:15.298 2025-09-07 18:06:15.312048 :CA4D6593:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23952 pso_num=28 pso_serial#=218 2025-09-07 18:06:15.312185 :CA4D6594:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23952 (legacy spawn) 2025-09-07 18:06:15.318698 :CA4D6595: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-09-07 18:06:15.318862 :CA4D659E: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-09-07 18:06:15.319663 :CA4D659F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:24.123552 :CA4D6845:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:24.114 2025-09-07 18:06:24.123554 :CA4D6846:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16164 pso_num=28 pso_serial#=219 2025-09-07 18:06:24.123707 :CA4D6847:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16164 (legacy spawn) 2025-09-07 18:06:24.130799 :CA4D6848: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-09-07 18:06:24.131004 :CA4D6850: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-09-07 18:06:24.131934 :CA4D6852:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:06:31.905874 :CA4D6AD2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:06:31.905 2025-09-07 18:06:31.905876 :CA4D6AD3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21576 pso_num=28 pso_serial#=220 2025-09-07 18:06:31.906014 :CA4D6AD4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21576 (legacy spawn) 2025-09-07 18:06:31.912866 :CA4D6AD5: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-09-07 18:06:31.913059 :CA4D6ADA: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-09-07 18:06:31.913931 :CA4D6ADF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:07:02.622487 :CA4D74E8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:07:02.619 2025-09-07 18:07:02.622489 :CA4D74E9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24360 pso_num=28 pso_serial#=221 2025-09-07 18:07:02.622676 :CA4D74EA:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24360 osp_idx=26 osp_ver=303974 osp_pg=0 (spawn #568631) 2025-09-07 18:07:02.622677 :CA4D74EB:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 18:07:02.622677 :CA4D74EC:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=176 time=1654073908 2025-09-07 18:07:02.622678 :CA4D74ED:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 18:07:02.622679 :CA4D74EE:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 18:07:02.622679 :CA4D74EF:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 18:07:02.622679 :CA4D74F0:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 18:07:02.622680 :CA4D74F1:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 18:07:02.623203 :CA4D74F2: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-09-07 18:07:02.623255 :CA4D74F8: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-09-07 18:07:02.623333 :CA4D74FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.627693 :CA4D751D: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-09-07 18:07:02.682372 :CA4D7528: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-09-07 18:07:02.684272 :CA4D7530: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-09-07 18:07:02.684791 :CA4D7538: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-09-07 18:07:02.684813 :CA4D753B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.684859 :CA4D7546:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 18:07:02.685835 :CA4D7551: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-09-07 18:07:02.685854 :CA4D7554:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.685883 :CA4D755F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 18:07:02.686324 :CA4D756A: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-09-07 18:07:02.688758 :CA4D7572: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-09-07 18:07:02.693943 :CA4D757A: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-09-07 18:07:02.695069 :CA4D7582: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-09-07 18:07:02.700373 :CA4D758A: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-09-07 18:07:02.701689 :CA4D7592: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-09-07 18:07:02.704737 :CA4D759A: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-09-07 18:07:02.705842 :CA4D75A2: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-09-07 18:07:02.705850 :CA4D75A3:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x000a.004.0017bb95 2025-09-07 18:07:02.708695 :CA4D75AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.708862 :CA4D75BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.709073 :CA4D75C5: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-09-07 18:07:02.709119 :CA4D75C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-07 18:07:02.709132 :CA4D75C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-07 18:07:02.711006 :CA4D75DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.711185 :CA4D75E1: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-09-07 18:07:02.711224 :CA4D75E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-07 18:07:02.711233 :CA4D75E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-07 18:07:02.711397 :CA4D75EB: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-09-07 18:07:02.711411 :CA4D75EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 18:07:02.711450 :CA4D75F9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 18:07:02.713635 :CA4D7604:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 18:07:02.821794 :CA4D76EB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 18:07:02.816 2025-09-07 18:07:02.821796 :CA4D76EC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23620 pso_num=28 pso_serial#=222 2025-09-07 18:07:02.821929 :CA4D76ED:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23620 osp_idx=26 osp_ver=303975 osp_pg=0 (spawn #568634) 2025-09-07 18:07:02.821930 :CA4D76EE:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 18:07:02.821931 :CA4D76EF:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=177 time=1654074127 2025-09-07 18:07:02.821931 :CA4D76F0:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 18:07:02.821932 :CA4D76F1:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 18:07:02.821932 :CA4D76F2:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 18:07:02.821932 :CA4D76F3:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 18:07:02.821933 :CA4D76F4:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 18:07:02.822451 :CA4D76FE: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: 23620, J002)