Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250810140856\orcl_j002_20112_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:35600M/63366M, Ph+PgF:41074M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 20112, image: ORACLE.EXE (J002) *** 2025-08-10 14:08:56.446 *** SESSION ID:(225.44167) 2025-08-10 14:08:56.446 *** 2025-08-10 14:08:56.446 Process diagnostic dump for ORACLE.EXE (J002), OS id=20112, pid: 28, proc_ser: 208, sid: 225, sess_ser: 44167 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20112 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.394378 sec, exc=0.394378 sec, total=0.394378 sec wait times: max=0.500000 sec, heur=3.897930 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=6 seq_num=7 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510106 sec, exc=0.510106 sec, total=0.510106 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493399 sec, exc=0.493399 sec, total=0.493399 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 44167 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 14:08:52 - 14:08:56] idle wait at each sample [session created at: 14:08:52] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-10 14:08:56.446 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 20112, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-10 14:05:30.924291 :BDE3676E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:05:33.994563 :BDE36D39:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:05:33.981 2025-08-10 14:05:33.994564 :BDE36D3A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8372 pso_num=28 pso_serial#=185 2025-08-10 14:05:33.994697 :BDE36D3B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8372 (legacy spawn) 2025-08-10 14:05:34.000973 :BDE36D5F: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-10 14:05:34.001170 :BDE36D64: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-10 14:05:34.001979 :BDE36D69:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:05:34.010715 :BDE36D6C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:05:33.996 2025-08-10 14:05:34.010717 :BDE36D6D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8216 pso_num=28 pso_serial#=186 2025-08-10 14:05:34.010856 :BDE36D6E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8216 (legacy spawn) 2025-08-10 14:05:34.017418 :BDE36D6F: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-10 14:05:34.017581 :BDE36D78: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-10 14:05:34.018375 :BDE36D79:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:05:44.993581 :BDE370C3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:05:44.984 2025-08-10 14:05:44.993583 :BDE370C4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6128 pso_num=28 pso_serial#=187 2025-08-10 14:05:44.993730 :BDE370C5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6128 (legacy spawn) 2025-08-10 14:05:45.000571 :BDE370C6: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-10 14:05:45.000752 :BDE370CB: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-10 14:05:45.001627 :BDE370D0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:05:46.219788 :BDE3712A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:05:46.211 2025-08-10 14:05:46.219790 :BDE3712B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19756 pso_num=28 pso_serial#=188 2025-08-10 14:05:46.219937 :BDE3712C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19756 (legacy spawn) 2025-08-10 14:05:46.226472 :BDE3712D: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-10 14:05:46.226640 :BDE37132: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-10 14:05:46.227481 :BDE37137:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:06:43.279207 :BDE38477:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:06:43.264 2025-08-10 14:06:43.279209 :BDE38478:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21500 pso_num=28 pso_serial#=189 2025-08-10 14:06:43.279347 :BDE38479:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21500 (legacy spawn) 2025-08-10 14:06:43.285874 :BDE3847A: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-10 14:06:43.286043 :BDE3847F: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-10 14:06:43.286896 :BDE38484:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:06:43.295583 :BDE38487:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:06:43.295 2025-08-10 14:06:43.295585 :BDE38488:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20256 pso_num=28 pso_serial#=190 2025-08-10 14:06:43.295717 :BDE38489:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20256 (legacy spawn) 2025-08-10 14:06:43.302259 :BDE3848A: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-10 14:06:43.302424 :BDE38493: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-10 14:06:43.303275 :BDE38494:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:06:46.381778 :BDE3857D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:06:46.368 2025-08-10 14:06:46.381781 :BDE3857E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6008 pso_num=28 pso_serial#=191 2025-08-10 14:06:46.381924 :BDE3857F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6008 (legacy spawn) 2025-08-10 14:06:46.388517 :BDE38580: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-10 14:06:46.388689 :BDE38585: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-10 14:06:46.400096 :BDE3858A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:06:46.408873 :BDE3858D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:06:46.399 2025-08-10 14:06:46.408875 :BDE3858E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7572 pso_num=28 pso_serial#=192 2025-08-10 14:06:46.409015 :BDE3858F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7572 (legacy spawn) 2025-08-10 14:06:46.415603 :BDE38590: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-10 14:06:46.415759 :BDE38599: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-10 14:06:46.416598 :BDE3859A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:06:47.126521 :BDE385DE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:06:47.118 2025-08-10 14:06:47.126523 :BDE385DF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16572 pso_num=28 pso_serial#=193 2025-08-10 14:06:47.126659 :BDE385E0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16572 (legacy spawn) 2025-08-10 14:06:47.133033 :BDE385E1: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-10 14:06:47.133202 :BDE385E6: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-10 14:06:47.134038 :BDE385EB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:06:54.315303 :BDE387FF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:06:54.305 2025-08-10 14:06:54.315306 :BDE38800:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17496 pso_num=28 pso_serial#=194 2025-08-10 14:06:54.315459 :BDE38801:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17496 (legacy spawn) 2025-08-10 14:06:54.322348 :BDE38802: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-10 14:06:54.322556 :BDE38807: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-10 14:06:54.323407 :BDE3880C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:07:49.297303 :BDE39966:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:07:49.289 2025-08-10 14:07:49.297305 :BDE39967:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7532 pso_num=28 pso_serial#=195 2025-08-10 14:07:49.297445 :BDE39968:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7532 (legacy spawn) 2025-08-10 14:07:49.303978 :BDE39969: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-10 14:07:49.304158 :BDE3996E: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-10 14:07:49.305014 :BDE39973:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:07:55.678454 :BDE39B61:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:07:55.663 2025-08-10 14:07:55.678457 :BDE39B62:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14676 pso_num=28 pso_serial#=196 2025-08-10 14:07:55.678639 :BDE39B63:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14676 (legacy spawn) 2025-08-10 14:07:55.685471 :BDE39B64: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-10 14:07:55.685504 :BDE39B67: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-10 14:07:55.685654 :BDE39B6A: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-10 14:07:55.686517 :BDE39B7B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:07:55.695663 :BDE39B7E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:07:55.694 2025-08-10 14:07:55.695664 :BDE39B7F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15600 pso_num=28 pso_serial#=197 2025-08-10 14:07:55.695796 :BDE39B80:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15600 (legacy spawn) 2025-08-10 14:07:55.702625 :BDE39B81: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-10 14:07:55.702800 :BDE39B8A: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-10 14:07:55.703690 :BDE39B8B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:07:58.884398 :BDE39C6E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:07:58.871 2025-08-10 14:07:58.884400 :BDE39C6F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20752 pso_num=28 pso_serial#=198 2025-08-10 14:07:58.884549 :BDE39C70:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20752 (legacy spawn) 2025-08-10 14:07:58.891645 :BDE39C71: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-10 14:07:58.891835 :BDE39C76: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-10 14:07:58.892730 :BDE39C7B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:07:58.901813 :BDE39C7E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:07:58.902 2025-08-10 14:07:58.901815 :BDE39C7F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20500 pso_num=28 pso_serial#=199 2025-08-10 14:07:58.901952 :BDE39C80:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20500 (legacy spawn) 2025-08-10 14:07:58.908526 :BDE39C81: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-10 14:07:58.908684 :BDE39C8A: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-10 14:07:58.909546 :BDE39C8B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:32.081843 :BDE3A6D9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:32.067 2025-08-10 14:08:32.081845 :BDE3A6DA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19356 pso_num=28 pso_serial#=200 2025-08-10 14:08:32.081978 :BDE3A6DB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19356 (legacy spawn) 2025-08-10 14:08:32.088484 :BDE3A6DC: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-10 14:08:32.088671 :BDE3A6E1: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-10 14:08:32.089490 :BDE3A6E6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:32.098280 :BDE3A6E9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:32.098 2025-08-10 14:08:32.098283 :BDE3A6EA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19656 pso_num=28 pso_serial#=201 2025-08-10 14:08:32.098428 :BDE3A6EB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19656 (legacy spawn) 2025-08-10 14:08:32.116096 :BDE3A6EC: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-10 14:08:32.116265 :BDE3A6F5: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-10 14:08:32.117133 :BDE3A6F6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:35.168227 :BDE3A7DE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:35.155 2025-08-10 14:08:35.168229 :BDE3A7DF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21100 pso_num=28 pso_serial#=202 2025-08-10 14:08:35.168373 :BDE3A7E0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21100 (legacy spawn) 2025-08-10 14:08:35.174846 :BDE3A7E1: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-10 14:08:35.175018 :BDE3A7E6: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-10 14:08:35.175824 :BDE3A7EB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:35.184612 :BDE3A7EE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:35.171 2025-08-10 14:08:35.184614 :BDE3A7EF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21264 pso_num=28 pso_serial#=203 2025-08-10 14:08:35.184750 :BDE3A7F0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21264 (legacy spawn) 2025-08-10 14:08:35.191341 :BDE3A7F1: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-10 14:08:35.191516 :BDE3A7FA: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-10 14:08:35.192339 :BDE3A7FB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:36.621495 :BDE3A889:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:36.613 2025-08-10 14:08:36.621497 :BDE3A88A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20488 pso_num=28 pso_serial#=204 2025-08-10 14:08:36.621629 :BDE3A88B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20488 (legacy spawn) 2025-08-10 14:08:36.628224 :BDE3A88C: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-10 14:08:36.628401 :BDE3A894: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-10 14:08:36.629231 :BDE3A896:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:43.327200 :BDE3AAB7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:43.322 2025-08-10 14:08:43.327202 :BDE3AAB8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21228 pso_num=28 pso_serial#=205 2025-08-10 14:08:43.327338 :BDE3AAB9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21228 osp_idx=26 osp_ver=218533 osp_pg=0 (spawn #474741) 2025-08-10 14:08:43.327339 :BDE3AABA: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-10 14:08:43.327339 :BDE3AABB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=18 time=3515592626 2025-08-10 14:08:43.327339 :BDE3AABC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-10 14:08:43.327342 :BDE3AABD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-10 14:08:43.327342 :BDE3AABE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-10 14:08:43.327342 :BDE3AABF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-10 14:08:43.327342 :BDE3AAC0:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-10 14:08:43.327829 :BDE3AAC1: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-10 14:08:43.327874 :BDE3AAC7: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-10 14:08:43.347730 :BDE3AAD8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:51.443795 :BDE3AD55:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:51.436 2025-08-10 14:08:51.443797 :BDE3AD56:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20828 pso_num=28 pso_serial#=206 2025-08-10 14:08:51.443937 :BDE3AD57:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20828 (legacy spawn) 2025-08-10 14:08:51.450398 :BDE3AD58: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-10 14:08:51.450577 :BDE3AD5D: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-10 14:08:51.451412 :BDE3AD62:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:52.347519 :BDE3ADBE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:52.342 2025-08-10 14:08:52.347522 :BDE3ADBF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17272 pso_num=28 pso_serial#=207 2025-08-10 14:08:52.347673 :BDE3ADC0:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=17272 osp_idx=26 osp_ver=218535 osp_pg=0 (spawn #474742) 2025-08-10 14:08:52.347673 :BDE3ADC1: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-10 14:08:52.347674 :BDE3ADC2:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=19 time=3515601658 2025-08-10 14:08:52.347674 :BDE3ADC3:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-10 14:08:52.347675 :BDE3ADC4:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-10 14:08:52.347675 :BDE3ADC5:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-10 14:08:52.347675 :BDE3ADC6:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-10 14:08:52.347676 :BDE3ADC7:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-10 14:08:52.348228 :BDE3ADC8: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-10 14:08:52.348281 :BDE3ADCE: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-10 14:08:52.352626 :BDE3ADDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-10 14:08:52.352680 :BDE3ADE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-10 14:08:52.352961 :BDE3ADE1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-10 14:08:52.352978 :BDE3ADE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-10 14:08:52.353207 :BDE3ADE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-10 14:08:52.353221 :BDE3ADE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-10 14:08:52.353428 :BDE3ADE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-10 14:08:52.353441 :BDE3ADE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-10 14:08:52.353650 :BDE3ADE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-10 14:08:52.353663 :BDE3ADE8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-10 14:08:52.353877 :BDE3ADE9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-10 14:08:52.353890 :BDE3ADEA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-10 14:08:52.356520 :BDE3AE18:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-10 14:08:52.550828 :BDE3AFCD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-10 14:08:52.545 2025-08-10 14:08:52.550829 :BDE3AFCE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20112 pso_num=28 pso_serial#=208 2025-08-10 14:08:52.550969 :BDE3AFCF:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20112 osp_idx=26 osp_ver=218536 osp_pg=0 (spawn #474744) 2025-08-10 14:08:52.550970 :BDE3AFD0: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-10 14:08:52.550970 :BDE3AFD1:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=20 time=3515601861 2025-08-10 14:08:52.550971 :BDE3AFD2:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-10 14:08:52.550971 :BDE3AFD3:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-10 14:08:52.550972 :BDE3AFD4:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-10 14:08:52.550972 :BDE3AFD5:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-10 14:08:52.550972 :BDE3AFD6:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-10 14:08:52.551642 :BDE3AFE0: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: 20112, J002)