Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250907220737\orcl_j002_24324_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:34951M/63366M, Ph+PgF:39715M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 24324, image: ORACLE.EXE (J002) *** 2025-09-07 22:07:37.881 *** SESSION ID:(225.21287) 2025-09-07 22:07:37.881 *** 2025-09-07 22:07:37.881 Process diagnostic dump for ORACLE.EXE (J002), OS id=24324, pid: 28, proc_ser: 49, sid: 225, sess_ser: 21287 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24324 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.175906 sec, exc=0.175906 sec, total=0.175906 sec wait times: max=0.500000 sec, heur=6.285980 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=12 seq_num=13 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500123 sec, exc=0.500123 sec, total=0.500123 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.501957 sec, exc=0.501957 sec, total=0.501957 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=7 seq_num=8 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500153 sec, exc=0.500153 sec, total=0.500153 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 225 serial 21287 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:07:31 - 22:07:37] idle wait at each sample [session created at: 22:07:31] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-07 22:07:37.881 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 24324, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-07 22:02:59.860353 :CA601493:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21720 (legacy spawn) 2025-09-07 22:02:59.867106 :CA601494:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:02:59.867276 :CA601499:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:02:59.868097 :CA60149E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:03:31.994701 :CA60221F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:03:31.981 2025-09-07 22:03:31.994703 :CA602220:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23364 pso_num=28 pso_serial#=28 2025-09-07 22:03:31.994846 :CA602221:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23364 (legacy spawn) 2025-09-07 22:03:32.001506 :CA602222:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:03:32.001676 :CA602227:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:03:32.002454 :CA60222C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:03:32.011386 :CA60222F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:03:31.996 2025-09-07 22:03:32.011389 :CA602230:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23676 pso_num=28 pso_serial#=29 2025-09-07 22:03:32.011533 :CA602231:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23676 (legacy spawn) 2025-09-07 22:03:32.018162 :CA602232:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:03:32.018323 :CA60223B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:03:32.019130 :CA60223C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:03:33.928353 :CA60236F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:03:33.918 2025-09-07 22:03:33.928356 :CA602370:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22492 pso_num=28 pso_serial#=30 2025-09-07 22:03:33.928532 :CA602371:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22492 (legacy spawn) 2025-09-07 22:03:33.935867 :CA602372:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:03:33.936082 :CA60237A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:03:33.937059 :CA60237C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:03:43.361150 :CA602647:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:03:43.352 2025-09-07 22:03:43.361152 :CA602648:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19120 pso_num=28 pso_serial#=31 2025-09-07 22:03:43.361295 :CA602649:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19120 (legacy spawn) 2025-09-07 22:03:43.368456 :CA602656:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:03:43.368658 :CA60265F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:03:43.369625 :CA602660:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:04:41.376261 :CA60394C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:04:41.371 2025-09-07 22:04:41.376263 :CA60394D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24332 pso_num=28 pso_serial#=32 2025-09-07 22:04:41.376414 :CA60394E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24332 (legacy spawn) 2025-09-07 22:04:41.389506 :CA60394F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:04:41.389737 :CA603954:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:04:41.390627 :CA603959:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:04:41.399732 :CA60395C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:04:41.387 2025-09-07 22:04:41.399735 :CA60395D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23780 pso_num=28 pso_serial#=33 2025-09-07 22:04:41.399870 :CA60395E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23780 (legacy spawn) 2025-09-07 22:04:41.407236 :CA60395F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:04:41.407412 :CA603968:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:04:41.408358 :CA603969:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:04:42.115891 :CA6039D9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:04:42.106 2025-09-07 22:04:42.115894 :CA6039DA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7692 pso_num=28 pso_serial#=34 2025-09-07 22:04:42.116034 :CA6039DB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7692 (legacy spawn) 2025-09-07 22:04:42.122650 :CA6039DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:04:42.122811 :CA6039E5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:04:42.123597 :CA6039E6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:04:44.511467 :CA603A66:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:04:44.498 2025-09-07 22:04:44.511469 :CA603A67:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22064 pso_num=28 pso_serial#=35 2025-09-07 22:04:44.511610 :CA603A68:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22064 (legacy spawn) 2025-09-07 22:04:44.518349 :CA603A69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:04:44.518525 :CA603A6E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:04:44.519319 :CA603A73:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:04:44.528180 :CA603A76:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:04:44.514 2025-09-07 22:04:44.528182 :CA603A77:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23612 pso_num=28 pso_serial#=36 2025-09-07 22:04:44.528368 :CA603A78:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23612 (legacy spawn) 2025-09-07 22:04:44.534851 :CA603A79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:04:44.535013 :CA603A82:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:04:44.535831 :CA603A83:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:04:45.553609 :CA603B03:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:04:45.545 2025-09-07 22:04:45.553611 :CA603B04:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22272 pso_num=28 pso_serial#=37 2025-09-07 22:04:45.553752 :CA603B05:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22272 (legacy spawn) 2025-09-07 22:04:45.560487 :CA603B06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:04:45.560679 :CA603B16:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:04:45.561514 :CA603B1C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:05:47.718283 :CA60553A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:05:47.705 2025-09-07 22:05:47.718286 :CA60553B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23904 pso_num=28 pso_serial#=38 2025-09-07 22:05:47.718428 :CA60553C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23904 (legacy spawn) 2025-09-07 22:05:47.726658 :CA605549:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:47.726859 :CA60554E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:05:47.727551 :CA605553:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:05:50.435388 :CA605622:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:05:50.426 2025-09-07 22:05:50.435390 :CA605623:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22072 pso_num=28 pso_serial#=39 2025-09-07 22:05:50.435529 :CA605624:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22072 (legacy spawn) 2025-09-07 22:05:50.442152 :CA605625:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:50.442322 :CA60562A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:05:50.443157 :CA60562F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:05:54.394981 :CA605797:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:05:54.380 2025-09-07 22:05:54.394984 :CA605798:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22640 pso_num=28 pso_serial#=40 2025-09-07 22:05:54.395134 :CA605799:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22640 (legacy spawn) 2025-09-07 22:05:54.401765 :CA60579A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:54.401938 :CA60579F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:05:54.402739 :CA6057A4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:05:54.411731 :CA6057A7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:05:54.412 2025-09-07 22:05:54.411733 :CA6057A8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22116 pso_num=28 pso_serial#=41 2025-09-07 22:05:54.411871 :CA6057A9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22116 (legacy spawn) 2025-09-07 22:05:54.418399 :CA6057AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:54.418431 :CA6057AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:54.418580 :CA6057B4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:05:54.419375 :CA6057C1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:05:57.691159 :CA6058C7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:05:57.677 2025-09-07 22:05:57.691161 :CA6058C8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24320 pso_num=28 pso_serial#=42 2025-09-07 22:05:57.691304 :CA6058C9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24320 (legacy spawn) 2025-09-07 22:05:57.698038 :CA6058CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:57.698212 :CA6058D3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:05:57.699008 :CA6058D4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:05:57.716927 :CA6058D7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:05:57.709 2025-09-07 22:05:57.716929 :CA6058D8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20336 pso_num=28 pso_serial#=43 2025-09-07 22:05:57.717080 :CA6058D9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20336 (legacy spawn) 2025-09-07 22:05:57.723913 :CA6058DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:05:57.724088 :CA6058E3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:05:57.724973 :CA6058E4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:06:33.991409 :CA6064E2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:06:33.978 2025-09-07 22:06:33.991411 :CA6064E3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6364 pso_num=28 pso_serial#=44 2025-09-07 22:06:33.991547 :CA6064E4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6364 (legacy spawn) 2025-09-07 22:06:34.011209 :CA6064E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:34.011375 :CA6064EE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:06:34.012215 :CA6064EF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:06:34.021204 :CA6064F2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:06:34.009 2025-09-07 22:06:34.021206 :CA6064F3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24488 pso_num=28 pso_serial#=45 2025-09-07 22:06:34.021350 :CA6064F4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24488 (legacy spawn) 2025-09-07 22:06:34.028004 :CA6064F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:34.028172 :CA6064FE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:06:34.029001 :CA6064FF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:06:49.883265 :CA6069DA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:06:49.874 2025-09-07 22:06:49.883268 :CA6069DB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22368 pso_num=28 pso_serial#=46 2025-09-07 22:06:49.883401 :CA6069DC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22368 (legacy spawn) 2025-09-07 22:06:49.890040 :CA6069DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:49.890226 :CA6069E5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:06:49.891055 :CA6069E6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:06:58.634247 :CA606CB0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:06:58.623 2025-09-07 22:06:58.634249 :CA606CB1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21564 pso_num=28 pso_serial#=47 2025-09-07 22:06:58.634388 :CA606CB2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21564 (legacy spawn) 2025-09-07 22:06:58.641409 :CA606CB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:06:58.641590 :CA606CB8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-07 22:06:58.642461 :CA606CBD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:07:31.493418 :CA6077D8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:07:31.488 2025-09-07 22:07:31.493419 :CA6077D9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24168 pso_num=28 pso_serial#=48 2025-09-07 22:07:31.493558 :CA6077DA:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24168 osp_idx=26 osp_ver=305331 osp_pg=0 (spawn #569187) 2025-09-07 22:07:31.493559 :CA6077DB:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 22:07:31.493559 :CA6077DC:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=184 time=1668502783 2025-09-07 22:07:31.493560 :CA6077DD:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 22:07:31.493560 :CA6077DE:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 22:07:31.493561 :CA6077DF:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 22:07:31.493561 :CA6077E0:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 22:07:31.493562 :CA6077E1:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 22:07:31.494165 :CA6077E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.494210 :CA6077E8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 22:07:31.494276 :CA6077EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.498884 :CA60780D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.554222 :CA607818:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.556119 :CA607820:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.556638 :CA607828:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.556660 :CA60782B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.556698 :CA607836:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 22:07:31.557712 :CA607841:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.557730 :CA607844:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.557760 :CA60784F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 22:07:31.558208 :CA60785A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.560592 :CA607862:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.565638 :CA60786A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.571057 :CA607872:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.572347 :CA60787A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.575377 :CA607882:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.576463 :CA60788A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.576471 :CA60788B:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x000a.01d.0017bbd4 2025-09-07 22:07:31.579432 :CA607893:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.579677 :CA60789E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.579720 :CA6078A1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-07 22:07:31.579732 :CA6078A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-07 22:07:31.582245 :CA6078B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.582465 :CA6078BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.582513 :CA6078BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-07 22:07:31.582526 :CA6078BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-07 22:07:31.582692 :CA6078C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.582706 :CA6078C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-07 22:07:31.582751 :CA6078D3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-07 22:07:31.584913 :CA6078DD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-07 22:07:31.598789 :CA607943:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-07 22:07:31.598 2025-09-07 22:07:31.598790 :CA607944:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24324 pso_num=28 pso_serial#=49 2025-09-07 22:07:31.598947 :CA607945:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24324 osp_idx=26 osp_ver=305332 osp_pg=0 (spawn #569190) 2025-09-07 22:07:31.598949 :CA607946:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-07 22:07:31.598949 :CA607947:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=185 time=1668502893 2025-09-07 22:07:31.598950 :CA607948:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-07 22:07:31.598950 :CA607949:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-07 22:07:31.598951 :CA60794A:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-07 22:07:31.598951 :CA60794B:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-07 22:07:31.598952 :CA60794C:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-07 22:07:31.599539 :CA60794D: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 2025-09-07 22:07:31.709150 :CA6079FD:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 24324, J002)