Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712100945\orcl_j002_17640_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:36426M/63366M, Ph+PgF:42504M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 17640, image: ORACLE.EXE (J002) *** 2025-07-12 10:09:45.578 *** SESSION ID:(219.39465) 2025-07-12 10:09:45.578 *** 2025-07-12 10:09:45.578 Process diagnostic dump for ORACLE.EXE (J002), OS id=17640, pid: 27, proc_ser: 246, sid: 219, sess_ser: 39465 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17640 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.408715 sec, exc=0.408715 sec, total=0.408715 sec wait times: max=0.500000 sec, heur=3.902471 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.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.500103 sec, exc=0.500103 sec, total=0.500103 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=4 seq_num=5 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 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=3 seq_num=4 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 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.499972 sec, exc=0.499972 sec, total=0.499972 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 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.493603 sec, exc=0.493603 sec, total=0.493603 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 219 serial 39465 --------------------------------------------------- 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, 10:09:41 - 10:09:45] idle wait at each sample [session created at: 10:09:41] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-12 10:09:45.578 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 17640, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-12 10:06:46.504662 :B0F17A91:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:06:46.522381 :B0F17A94:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:06:46.513 2025-07-12 10:06:46.522384 :B0F17A95:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9092 pso_num=27 pso_serial#=223 2025-07-12 10:06:46.522533 :B0F17A96:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9092 (legacy spawn) 2025-07-12 10:06:46.529657 :B0F17A97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:06:46.529838 :B0F17AA0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:06:46.530703 :B0F17AA1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:06:49.131341 :B0F17B7F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:06:49.122 2025-07-12 10:06:49.131343 :B0F17B80:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6856 pso_num=27 pso_serial#=224 2025-07-12 10:06:49.131482 :B0F17B81:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6856 (legacy spawn) 2025-07-12 10:06:49.137893 :B0F17B82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:06:49.138060 :B0F17B8A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:06:49.138849 :B0F17B8C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:06:49.589046 :B0F17B9A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:06:49.575 2025-07-12 10:06:49.589048 :B0F17B9B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12136 pso_num=27 pso_serial#=225 2025-07-12 10:06:49.589184 :B0F17B9C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12136 (legacy spawn) 2025-07-12 10:06:49.595599 :B0F17B9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:06:49.595770 :B0F17BA6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:06:49.596632 :B0F17BA7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:06:49.605248 :B0F17BAA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:06:49.591 2025-07-12 10:06:49.605250 :B0F17BAB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7652 pso_num=27 pso_serial#=226 2025-07-12 10:06:49.605389 :B0F17BAC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7652 (legacy spawn) 2025-07-12 10:06:49.611854 :B0F17BAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:06:49.612014 :B0F17BB6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:06:49.612880 :B0F17BB7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:07:25.781572 :B0F187CD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:07:25.770 2025-07-12 10:07:25.781574 :B0F187CE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14260 pso_num=27 pso_serial#=227 2025-07-12 10:07:25.781713 :B0F187CF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14260 (legacy spawn) 2025-07-12 10:07:25.788393 :B0F187D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:07:25.788557 :B0F187D9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:07:25.789369 :B0F187DA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:07:25.798038 :B0F187DD:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:07:25.785 2025-07-12 10:07:25.798040 :B0F187DE:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4556 pso_num=27 pso_serial#=228 2025-07-12 10:07:25.798176 :B0F187DF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4556 (legacy spawn) 2025-07-12 10:07:25.804661 :B0F187E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:07:25.804825 :B0F187E9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:07:25.805621 :B0F187EA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:07:42.098527 :B0F18D22:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:07:42.086 2025-07-12 10:07:42.098529 :B0F18D23:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6548 pso_num=27 pso_serial#=229 2025-07-12 10:07:42.098677 :B0F18D24:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6548 (legacy spawn) 2025-07-12 10:07:42.105180 :B0F18D25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:07:42.105352 :B0F18D2A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:07:42.106139 :B0F18D2F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:07:57.389961 :B0F19208:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:07:57.380 2025-07-12 10:07:57.389963 :B0F19209:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16624 pso_num=27 pso_serial#=230 2025-07-12 10:07:57.390107 :B0F1920A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16624 (legacy spawn) 2025-07-12 10:07:57.396644 :B0F1920B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:07:57.396821 :B0F19210:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:07:57.397611 :B0F19215:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:07:58.886760 :B0F1926A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:07:58.880 2025-07-12 10:07:58.886762 :B0F1926B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11308 pso_num=27 pso_serial#=231 2025-07-12 10:07:58.886914 :B0F1926C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11308 (legacy spawn) 2025-07-12 10:07:58.893819 :B0F1926D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:07:58.894009 :B0F19272:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:07:58.894977 :B0F19277:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:07:58.904321 :B0F1927A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:07:58.895 2025-07-12 10:07:58.904323 :B0F1927B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18184 pso_num=27 pso_serial#=232 2025-07-12 10:07:58.904487 :B0F1927C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18184 (legacy spawn) 2025-07-12 10:07:58.911552 :B0F1927D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:07:58.911725 :B0F19286:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:07:58.912700 :B0F19287:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:01.983463 :B0F1937C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:01.970 2025-07-12 10:08:01.983465 :B0F1937D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16156 pso_num=27 pso_serial#=233 2025-07-12 10:08:01.983606 :B0F1937E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16156 (legacy spawn) 2025-07-12 10:08:01.990132 :B0F1937F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:01.990312 :B0F19384:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:01.991134 :B0F19389:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:01.999764 :B0F1938C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:01.985 2025-07-12 10:08:01.999766 :B0F1938D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13400 pso_num=27 pso_serial#=234 2025-07-12 10:08:01.999904 :B0F1938E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13400 (legacy spawn) 2025-07-12 10:08:02.006349 :B0F1938F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:02.006516 :B0F19398:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:02.007325 :B0F19399:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:12.402549 :B0F196F8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:12.389 2025-07-12 10:08:12.402551 :B0F196F9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16720 pso_num=27 pso_serial#=235 2025-07-12 10:08:12.402689 :B0F196FA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16720 (legacy spawn) 2025-07-12 10:08:12.409250 :B0F196FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:12.409437 :B0F19700:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:12.410224 :B0F19705:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:12.418926 :B0F19708:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:12.404 2025-07-12 10:08:12.418928 :B0F19709:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14300 pso_num=27 pso_serial#=236 2025-07-12 10:08:12.419113 :B0F1970A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14300 (legacy spawn) 2025-07-12 10:08:12.425504 :B0F1970B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:12.425668 :B0F19713:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:12.426480 :B0F19715:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:31.536198 :B0F19D62:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:31.527 2025-07-12 10:08:31.536200 :B0F19D63:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12780 pso_num=27 pso_serial#=237 2025-07-12 10:08:31.536343 :B0F19D64:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12780 (legacy spawn) 2025-07-12 10:08:31.560359 :B0F19D65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:31.560531 :B0F19D6E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:31.561397 :B0F19D6F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:35.088176 :B0F19E62:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:35.073 2025-07-12 10:08:35.088178 :B0F19E63:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16960 pso_num=27 pso_serial#=238 2025-07-12 10:08:35.088358 :B0F19E64:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16960 (legacy spawn) 2025-07-12 10:08:35.095308 :B0F19E65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:35.095492 :B0F19E6D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:35.096406 :B0F19E6F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:35.112997 :B0F19E72:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:35.104 2025-07-12 10:08:35.112999 :B0F19E73:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18256 pso_num=27 pso_serial#=239 2025-07-12 10:08:35.113142 :B0F19E74:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18256 (legacy spawn) 2025-07-12 10:08:35.120015 :B0F19E75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:35.120190 :B0F19E7D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:35.121119 :B0F19E7F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:38.290524 :B0F19F89:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:38.289 2025-07-12 10:08:38.290526 :B0F19F8A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10908 pso_num=27 pso_serial#=240 2025-07-12 10:08:38.290666 :B0F19F8B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10908 (legacy spawn) 2025-07-12 10:08:38.297150 :B0F19F8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:38.297317 :B0F19F92:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:38.298144 :B0F19F96:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:38.306810 :B0F19F99:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:38.304 2025-07-12 10:08:38.306812 :B0F19F9A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14456 pso_num=27 pso_serial#=241 2025-07-12 10:08:38.306949 :B0F19F9B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14456 (legacy spawn) 2025-07-12 10:08:38.313364 :B0F19F9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:38.313535 :B0F19FA5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:38.314377 :B0F19FA6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:08:44.298307 :B0F1A183:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:08:44.290 2025-07-12 10:08:44.298309 :B0F1A184:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17072 pso_num=27 pso_serial#=242 2025-07-12 10:08:44.298442 :B0F1A185:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17072 (legacy spawn) 2025-07-12 10:08:44.304922 :B0F1A186:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:08:44.305094 :B0F1A18B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:08:44.322036 :B0F1A1B6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:09:32.437288 :B0F1B142:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:09:32.432 2025-07-12 10:09:32.437290 :B0F1B143:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17120 pso_num=27 pso_serial#=243 2025-07-12 10:09:32.437423 :B0F1B144:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17120 osp_idx=25 osp_ver=150701 osp_pg=0 (spawn #377519) 2025-07-12 10:09:32.437424 :B0F1B145:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:09:32.437424 :B0F1B146:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=130 time=995748329 2025-07-12 10:09:32.437425 :B0F1B147:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:09:32.437425 :B0F1B148:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:09:32.437426 :B0F1B149:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:09:32.437426 :B0F1B14A:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:09:32.437426 :B0F1B14B:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:09:32.437991 :B0F1B14C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:09:32.438034 :B0F1B152:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 10:09:32.457807 :B0F1B163:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:09:39.734100 :B0F1B379:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:09:39.721 2025-07-12 10:09:39.734102 :B0F1B37A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4660 pso_num=27 pso_serial#=244 2025-07-12 10:09:39.734245 :B0F1B37B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4660 (legacy spawn) 2025-07-12 10:09:39.753891 :B0F1B37C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:39.754061 :B0F1B381:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-12 10:09:39.754961 :B0F1B386:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:09:41.473511 :B0F1B44D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:09:41.468 2025-07-12 10:09:41.473513 :B0F1B44E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17976 pso_num=27 pso_serial#=245 2025-07-12 10:09:41.473658 :B0F1B44F:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17976 osp_idx=25 osp_ver=150703 osp_pg=0 (spawn #377520) 2025-07-12 10:09:41.473659 :B0F1B450:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:09:41.473659 :B0F1B451:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=131 time=995757361 2025-07-12 10:09:41.473660 :B0F1B452:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:09:41.473660 :B0F1B453:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:09:41.473660 :B0F1B454:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:09:41.473661 :B0F1B455:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:09:41.473661 :B0F1B456:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:09:41.474175 :B0F1B457:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:09:41.474224 :B0F1B45D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 10:09:41.478259 :B0F1B46F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-12 10:09:41.478308 :B0F1B470:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-12 10:09:41.478570 :B0F1B471:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-12 10:09:41.478585 :B0F1B472:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-12 10:09:41.478813 :B0F1B473:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-12 10:09:41.478826 :B0F1B474:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-12 10:09:41.479034 :B0F1B475:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-12 10:09:41.479047 :B0F1B476:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-12 10:09:41.479251 :B0F1B477:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-12 10:09:41.479265 :B0F1B478:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-12 10:09:41.479470 :B0F1B479:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-12 10:09:41.479483 :B0F1B47A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-12 10:09:41.481985 :B0F1B4A8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-12 10:09:41.676829 :B0F1B8F2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-12 10:09:41.671 2025-07-12 10:09:41.676830 :B0F1B8F3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17640 pso_num=27 pso_serial#=246 2025-07-12 10:09:41.676964 :B0F1B8F4:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17640 osp_idx=25 osp_ver=150704 osp_pg=0 (spawn #377522) 2025-07-12 10:09:41.676965 :B0F1B8F5:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:09:41.676965 :B0F1B8F6:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=132 time=995757564 2025-07-12 10:09:41.676966 :B0F1B8F7:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:09:41.676966 :B0F1B8F8:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:09:41.676966 :B0F1B8F9:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:09:41.676967 :B0F1B8FA:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:09:41.676967 :B0F1B8FB:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:09:41.677491 :B0F1B905:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 17640, J002)