Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250427140927\orcl_j002_2288_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:37594M/63366M, Ph+PgF:45300M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 2288, image: ORACLE.EXE (J002) *** 2025-04-27 14:09:27.646 *** SESSION ID:(219.61803) 2025-04-27 14:09:27.646 *** 2025-04-27 14:09:27.646 Process diagnostic dump for ORACLE.EXE (J002), OS id=2288, pid: 27, proc_ser: 19, sid: 219, sess_ser: 61803 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 2288 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.406753 sec, exc=0.406753 sec, total=0.406753 sec wait times: max=0.500000 sec, heur=7.894262 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=14 seq_num=15 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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.000004 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500013 sec, exc=0.500013 sec, total=0.500013 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=11 seq_num=12 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.501077 sec, exc=0.501077 sec, total=0.501077 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.498968 sec, exc=0.498968 sec, total=0.498968 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.485404 sec, exc=0.485404 sec, total=0.485404 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 219 serial 61803 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 14:09:19 - 14:09:27] idle wait at each sample [session created at: 14:09:19] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-27 14:09:27.646 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 2288, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-27 14:04:45.769489 :8F7CE074:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1872 (legacy spawn) 2025-04-27 14:04:45.775863 :8F7CE075: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-04-27 14:04:45.776039 :8F7CE07A: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-04-27 14:04:45.776819 :8F7CE07F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:04:45.794893 :8F7CE08E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:04:45.788 2025-04-27 14:04:45.794894 :8F7CE08F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9712 pso_num=27 pso_serial#=253 2025-04-27 14:04:45.795035 :8F7CE090:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9712 (legacy spawn) 2025-04-27 14:04:45.801495 :8F7CE091: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-04-27 14:04:45.801662 :8F7CE09A: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-04-27 14:04:45.802480 :8F7CE09B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:05:24.866327 :8F7CF36A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:05:24.858 2025-04-27 14:05:24.866329 :8F7CF36B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7880 pso_num=27 pso_serial#=254 2025-04-27 14:05:24.866462 :8F7CF36C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7880 (legacy spawn) 2025-04-27 14:05:24.872958 :8F7CF36D: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-04-27 14:05:24.873137 :8F7CF372: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-04-27 14:05:24.873934 :8F7CF377:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:05:51.331304 :8F7CFCE3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:05:51.322 2025-04-27 14:05:51.331306 :8F7CFCE4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9788 pso_num=27 pso_serial#=255 2025-04-27 14:05:51.331451 :8F7CFCE5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9788 (legacy spawn) 2025-04-27 14:05:51.337969 :8F7CFCE6: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-04-27 14:05:51.338132 :8F7CFCEF: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-04-27 14:05:51.338944 :8F7CFCF0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:06:25.528113 :8F7D08B7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:06:25.514 2025-04-27 14:06:25.528115 :8F7D08B8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2404 pso_num=27 pso_serial#=1 2025-04-27 14:06:25.528247 :8F7D08B9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2404 (legacy spawn) 2025-04-27 14:06:25.534733 :8F7D08BA: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-04-27 14:06:25.534900 :8F7D08C2: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-04-27 14:06:25.535692 :8F7D08C4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:06:27.091201 :8F7D093C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:06:27.084 2025-04-27 14:06:27.091202 :8F7D093D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10748 pso_num=27 pso_serial#=2 2025-04-27 14:06:27.091339 :8F7D093E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10748 (legacy spawn) 2025-04-27 14:06:27.097766 :8F7D093F: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-04-27 14:06:27.097950 :8F7D0948: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-04-27 14:06:27.098737 :8F7D0949:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:06:31.771441 :8F7D0AD4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:06:31.757 2025-04-27 14:06:31.771443 :8F7D0AD5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6844 pso_num=27 pso_serial#=3 2025-04-27 14:06:31.771580 :8F7D0AD6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6844 (legacy spawn) 2025-04-27 14:06:31.777947 :8F7D0AD7: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-04-27 14:06:31.778116 :8F7D0ADC: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-04-27 14:06:31.778891 :8F7D0AE1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:06:31.787494 :8F7D0AE4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:06:31.773 2025-04-27 14:06:31.787496 :8F7D0AE5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4928 pso_num=27 pso_serial#=4 2025-04-27 14:06:31.787634 :8F7D0AE6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4928 (legacy spawn) 2025-04-27 14:06:31.794141 :8F7D0AE7: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-04-27 14:06:31.794313 :8F7D0AF0: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-04-27 14:06:31.795140 :8F7D0AF1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:06:34.896909 :8F7D0BE9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:06:34.884 2025-04-27 14:06:34.896912 :8F7D0BEA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8756 pso_num=27 pso_serial#=5 2025-04-27 14:06:34.897053 :8F7D0BEB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8756 (legacy spawn) 2025-04-27 14:06:34.903547 :8F7D0BF7: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-04-27 14:06:34.903722 :8F7D0BFC: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-04-27 14:06:34.904514 :8F7D0C01:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:06:34.913140 :8F7D0C04:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:06:34.900 2025-04-27 14:06:34.913142 :8F7D0C05:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5248 pso_num=27 pso_serial#=6 2025-04-27 14:06:34.913321 :8F7D0C06:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5248 (legacy spawn) 2025-04-27 14:06:34.934968 :8F7D0C07: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-04-27 14:06:34.935130 :8F7D0C10: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-04-27 14:06:34.935909 :8F7D0C11:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:07:29.256463 :8F7D1E7F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:07:29.248 2025-04-27 14:07:29.256465 :8F7D1E80:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=220 pso_num=27 pso_serial#=7 2025-04-27 14:07:29.256600 :8F7D1E81:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=220 (legacy spawn) 2025-04-27 14:07:29.262967 :8F7D1E82: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-04-27 14:07:29.263137 :8F7D1E87: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-04-27 14:07:29.264220 :8F7D1E98:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:07:33.926172 :8F7D200B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:07:33.917 2025-04-27 14:07:33.926174 :8F7D200C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7964 pso_num=27 pso_serial#=8 2025-04-27 14:07:33.926308 :8F7D200D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7964 (legacy spawn) 2025-04-27 14:07:33.932876 :8F7D200E: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-04-27 14:07:33.933052 :8F7D2017: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-04-27 14:07:33.934214 :8F7D2018:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:07:44.186747 :8F7D2360:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:07:44.176 2025-04-27 14:07:44.186749 :8F7D2361:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10508 pso_num=27 pso_serial#=9 2025-04-27 14:07:44.186920 :8F7D2362:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10508 (legacy spawn) 2025-04-27 14:07:44.193408 :8F7D2363: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-04-27 14:07:44.193578 :8F7D2368: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-04-27 14:07:44.194394 :8F7D236D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:07:44.202987 :8F7D2370:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:07:44.192 2025-04-27 14:07:44.202989 :8F7D2371:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7276 pso_num=27 pso_serial#=10 2025-04-27 14:07:44.203132 :8F7D2372:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7276 (legacy spawn) 2025-04-27 14:07:44.209712 :8F7D2373: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-04-27 14:07:44.209879 :8F7D237C: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-04-27 14:07:44.210662 :8F7D237D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:07:47.304084 :8F7D247D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:07:47.291 2025-04-27 14:07:47.304086 :8F7D247E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9636 pso_num=27 pso_serial#=11 2025-04-27 14:07:47.304217 :8F7D247F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9636 (legacy spawn) 2025-04-27 14:07:47.310700 :8F7D2480: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-04-27 14:07:47.310873 :8F7D2485: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-04-27 14:07:47.311709 :8F7D248A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:07:47.320179 :8F7D248D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:07:47.306 2025-04-27 14:07:47.320181 :8F7D248E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9228 pso_num=27 pso_serial#=12 2025-04-27 14:07:47.320330 :8F7D248F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9228 (legacy spawn) 2025-04-27 14:07:47.326874 :8F7D2490: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-04-27 14:07:47.327033 :8F7D2499: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-04-27 14:07:47.327906 :8F7D249A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:08:23.695950 :8F7D31D2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:08:23.683 2025-04-27 14:08:23.695952 :8F7D31D3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9836 pso_num=27 pso_serial#=13 2025-04-27 14:08:23.696084 :8F7D31D4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9836 (legacy spawn) 2025-04-27 14:08:23.702567 :8F7D31D5: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-04-27 14:08:23.702728 :8F7D31DE: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-04-27 14:08:23.703503 :8F7D31DF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:08:23.712197 :8F7D31E2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:08:23.698 2025-04-27 14:08:23.712199 :8F7D31E3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10384 pso_num=27 pso_serial#=14 2025-04-27 14:08:23.712337 :8F7D31E4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10384 (legacy spawn) 2025-04-27 14:08:23.719212 :8F7D31E5: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-04-27 14:08:23.719368 :8F7D31EE: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-04-27 14:08:23.720423 :8F7D31EF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:08:31.439892 :8F7D343D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:08:31.432 2025-04-27 14:08:31.439894 :8F7D343E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6028 pso_num=27 pso_serial#=15 2025-04-27 14:08:31.440036 :8F7D343F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6028 (legacy spawn) 2025-04-27 14:08:31.446410 :8F7D3440: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-04-27 14:08:31.446579 :8F7D3446: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-04-27 14:08:31.447268 :8F7D346D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:08:42.035040 :8F7D37D1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:08:42.022 2025-04-27 14:08:42.035042 :8F7D37D2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2472 pso_num=27 pso_serial#=16 2025-04-27 14:08:42.035174 :8F7D37D3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2472 (legacy spawn) 2025-04-27 14:08:42.041675 :8F7D37D4: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-04-27 14:08:42.041856 :8F7D37D9: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-04-27 14:08:42.042633 :8F7D37DE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:08:44.725479 :8F7D38C0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:08:44.717 2025-04-27 14:08:44.725481 :8F7D38C1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10296 pso_num=27 pso_serial#=17 2025-04-27 14:08:44.725620 :8F7D38C2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10296 (legacy spawn) 2025-04-27 14:08:44.732097 :8F7D38C3: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-04-27 14:08:44.732262 :8F7D38C8: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-04-27 14:08:44.733066 :8F7D38CD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:09:19.559030 :8F7D44AB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:09:19.556 2025-04-27 14:09:19.559032 :8F7D44AC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10540 pso_num=27 pso_serial#=18 2025-04-27 14:09:19.559228 :8F7D44AD:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10540 osp_idx=25 osp_ver=30872 osp_pg=0 (spawn #123894) 2025-04-27 14:09:19.559229 :8F7D44AE:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 14:09:19.559230 :8F7D44AF:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=17 time=3033761343 2025-04-27 14:09:19.559231 :8F7D44B0:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 14:09:19.559232 :8F7D44B1:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 14:09:19.559232 :8F7D44B2:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 14:09:19.559233 :8F7D44B3:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 14:09:19.559233 :8F7D44B4:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 14:09:19.559788 :8F7D44B5: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-04-27 14:09:19.559843 :8F7D44BB: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-04-27 14:09:19.559923 :8F7D44C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.564373 :8F7D44E0: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-04-27 14:09:19.616829 :8F7D44EB: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-04-27 14:09:19.618318 :8F7D44FF: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-04-27 14:09:19.618831 :8F7D4507: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-04-27 14:09:19.618850 :8F7D450A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.618890 :8F7D4515:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-27 14:09:19.619865 :8F7D4520: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-04-27 14:09:19.619883 :8F7D4523:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.619914 :8F7D452E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-27 14:09:19.620346 :8F7D4539: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-04-27 14:09:19.622648 :8F7D4541: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-04-27 14:09:19.627694 :8F7D4549: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-04-27 14:09:19.633067 :8F7D4551: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-04-27 14:09:19.634400 :8F7D4559: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-04-27 14:09:19.637232 :8F7D4561: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-04-27 14:09:19.638327 :8F7D4569: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-04-27 14:09:19.638336 :8F7D456A:db_trace:ktur.c@3079:ktuabt(): [10444:27:219] ABORT TRANSACTION - xid: 0x000a.01e.001663f2 2025-04-27 14:09:19.640964 :8F7D4572:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.641113 :8F7D4581:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.641334 :8F7D458C: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-04-27 14:09:19.641381 :8F7D458F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-04-27 14:09:19.641392 :8F7D4590:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-04-27 14:09:19.643348 :8F7D45A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.643506 :8F7D45A8: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-04-27 14:09:19.643547 :8F7D45AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:ktcc.c:879 2025-04-27 14:09:19.643557 :8F7D45AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:ktcc.c:879 2025-04-27 14:09:19.643720 :8F7D45B2: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-04-27 14:09:19.643735 :8F7D45B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-27 14:09:19.643775 :8F7D45C0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-27 14:09:19.645636 :8F7D45CB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-27 14:09:19.764123 :8F7D46D0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-27 14:09:19.759 2025-04-27 14:09:19.764125 :8F7D46D1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2288 pso_num=27 pso_serial#=19 2025-04-27 14:09:19.764259 :8F7D46D2:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=2288 osp_idx=25 osp_ver=30873 osp_pg=0 (spawn #123897) 2025-04-27 14:09:19.764260 :8F7D46D3:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 14:09:19.764260 :8F7D46D4:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=18 time=3033761562 2025-04-27 14:09:19.764260 :8F7D46D5:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 14:09:19.764261 :8F7D46D6:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 14:09:19.764261 :8F7D46D7:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 14:09:19.764261 :8F7D46D8:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 14:09:19.764261 :8F7D46D9:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 14:09:19.764740 :8F7D46E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 14:09:22.752455 :8F7D4970:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 2288, J002)