Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713140328\orcl_j002_5116_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:36379M/63366M, Ph+PgF:42432M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 5116, image: ORACLE.EXE (J002) *** 2025-07-13 14:03:28.919 *** SESSION ID:(219.56711) 2025-07-13 14:03:28.919 *** 2025-07-13 14:03:28.919 Process diagnostic dump for ORACLE.EXE (J002), OS id=5116, pid: 27, proc_ser: 197, sid: 219, sess_ser: 56711 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 5116 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.366794 sec, exc=0.366794 sec, total=0.366794 sec wait times: max=0.500000 sec, heur=3.870770 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.000009 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.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.000014 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.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.000013 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.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510130 sec, exc=0.510130 sec, total=0.510130 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.499995 sec, exc=0.499995 sec, total=0.499995 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=0 seq_num=1 snap_id=1 wait times: snap=0.493792 sec, exc=0.493792 sec, total=0.493792 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 56711 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [4 samples, 14:03:25 - 14:03:28] idle wait at each sample [session created at: 14:03:25] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 14:03:28.919 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 5116, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 13:59:25.532870 :B1753D2A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 13:59:25.541449 :B1753D2D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 13:59:25.528 2025-07-13 13:59:25.541451 :B1753D2E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13284 pso_num=27 pso_serial#=173 2025-07-13 13:59:25.541589 :B1753D2F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13284 (legacy spawn) 2025-07-13 13:59:25.548029 :B1753D30: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-13 13:59:25.548200 :B1753D39: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-13 13:59:25.549006 :B1753D3A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:17.346910 :B1755008:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:17.338 2025-07-13 14:00:17.346912 :B1755009:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13728 pso_num=27 pso_serial#=174 2025-07-13 14:00:17.347051 :B175500A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13728 (legacy spawn) 2025-07-13 14:00:17.353477 :B175500B: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-13 14:00:17.353642 :B1755011: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-13 14:00:17.354478 :B1755021:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:25.235796 :B1755256:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:25.227 2025-07-13 14:00:25.235798 :B1755257:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16212 pso_num=27 pso_serial#=175 2025-07-13 14:00:25.235933 :B1755258:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16212 (legacy spawn) 2025-07-13 14:00:25.242421 :B1755259: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-13 14:00:25.242600 :B1755262: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-13 14:00:25.243395 :B1755263:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:35.435316 :B17555CB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:35.421 2025-07-13 14:00:35.435318 :B17555CC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=636 pso_num=27 pso_serial#=176 2025-07-13 14:00:35.435502 :B17555CD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=636 (legacy spawn) 2025-07-13 14:00:35.441931 :B17555CE: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-13 14:00:35.442113 :B17555D6: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-13 14:00:35.442987 :B17555D8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:35.459505 :B17555DB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:35.452 2025-07-13 14:00:35.459507 :B17555DC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16492 pso_num=27 pso_serial#=177 2025-07-13 14:00:35.459643 :B17555DD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16492 (legacy spawn) 2025-07-13 14:00:35.466028 :B17555DE: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-13 14:00:35.466206 :B17555E7: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-13 14:00:35.467013 :B17555E8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:38.632041 :B17556E7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:38.618 2025-07-13 14:00:38.632043 :B17556E8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16720 pso_num=27 pso_serial#=178 2025-07-13 14:00:38.632186 :B17556E9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16720 (legacy spawn) 2025-07-13 14:00:38.638717 :B17556EA: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-13 14:00:38.638903 :B17556F3: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-13 14:00:38.639693 :B17556F4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:38.648327 :B17556F7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:38.644 2025-07-13 14:00:38.648329 :B17556F8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4432 pso_num=27 pso_serial#=179 2025-07-13 14:00:38.648470 :B17556F9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4432 (legacy spawn) 2025-07-13 14:00:38.654898 :B17556FA: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-13 14:00:38.655057 :B1755703: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-13 14:00:38.655854 :B1755704:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:51.641114 :B1755B05:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:51.632 2025-07-13 14:00:51.641116 :B1755B06:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11076 pso_num=27 pso_serial#=180 2025-07-13 14:00:51.641250 :B1755B07:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11076 (legacy spawn) 2025-07-13 14:00:51.647720 :B1755B08: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-13 14:00:51.647916 :B1755B0D: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-13 14:00:51.648727 :B1755B12:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:00:56.340543 :B1755C89:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:00:56.332 2025-07-13 14:00:56.340545 :B1755C8A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17560 pso_num=27 pso_serial#=181 2025-07-13 14:00:56.340685 :B1755C8B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17560 (legacy spawn) 2025-07-13 14:00:56.347085 :B1755C8C: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-13 14:00:56.347268 :B1755C91: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-13 14:00:56.348103 :B1755C96:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:01:11.806964 :B1756203:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:01:11.803 2025-07-13 14:01:11.806966 :B1756204:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17764 pso_num=27 pso_serial#=182 2025-07-13 14:01:11.807114 :B1756205:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17764 (legacy spawn) 2025-07-13 14:01:11.821009 :B1756206: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-13 14:01:11.928481 :B1756209: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-13 14:01:11.943384 :B1756210: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-13 14:01:11.944361 :B175621D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:01:11.953059 :B1756220:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:01:11.944 2025-07-13 14:01:11.953061 :B1756221:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1020 pso_num=27 pso_serial#=183 2025-07-13 14:01:11.953199 :B1756222:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1020 (legacy spawn) 2025-07-13 14:01:11.959759 :B1756223: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-13 14:01:11.959926 :B175622C: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-13 14:01:11.960757 :B175622D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:01:15.305682 :B1756332:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:01:15.292 2025-07-13 14:01:15.305684 :B1756333:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17648 pso_num=27 pso_serial#=184 2025-07-13 14:01:15.305822 :B1756334:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17648 (legacy spawn) 2025-07-13 14:01:15.312217 :B1756335: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-13 14:01:15.312385 :B175633A: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-13 14:01:15.313181 :B175633F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:01:15.321822 :B1756342:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:01:15.308 2025-07-13 14:01:15.321824 :B1756343:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9372 pso_num=27 pso_serial#=185 2025-07-13 14:01:15.321970 :B1756344:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9372 (legacy spawn) 2025-07-13 14:01:15.328464 :B1756345: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-13 14:01:15.328626 :B175634E: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-13 14:01:15.329425 :B175634F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:01:25.857238 :B1756690:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:01:25.849 2025-07-13 14:01:25.857240 :B1756691:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17272 pso_num=27 pso_serial#=186 2025-07-13 14:01:25.857384 :B1756692:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17272 (legacy spawn) 2025-07-13 14:01:25.863788 :B1756693: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-13 14:01:25.863983 :B175669A: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-13 14:01:25.864783 :B175669C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:01:27.435285 :B1756712:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:01:27.427 2025-07-13 14:01:27.435288 :B1756713:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17792 pso_num=27 pso_serial#=187 2025-07-13 14:01:27.435430 :B1756714:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17792 (legacy spawn) 2025-07-13 14:01:27.441876 :B1756715: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-13 14:01:27.442042 :B175671A: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-13 14:01:27.442854 :B175671F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:02:24.638284 :B175792A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:02:24.634 2025-07-13 14:02:24.638286 :B175792B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16700 pso_num=27 pso_serial#=188 2025-07-13 14:02:24.638429 :B175792C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16700 (legacy spawn) 2025-07-13 14:02:24.644933 :B175792D: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-13 14:02:24.645103 :B1757932: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-13 14:02:24.645911 :B1757937:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:02:24.654643 :B175793A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:02:24.650 2025-07-13 14:02:24.654645 :B175793B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1312 pso_num=27 pso_serial#=189 2025-07-13 14:02:24.654785 :B175793C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1312 (legacy spawn) 2025-07-13 14:02:24.661273 :B175793D: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-13 14:02:24.661436 :B1757946: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-13 14:02:24.662257 :B1757947:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:02:27.808276 :B1757A58:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:02:27.795 2025-07-13 14:02:27.808278 :B1757A59:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11772 pso_num=27 pso_serial#=190 2025-07-13 14:02:27.808411 :B1757A5A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11772 (legacy spawn) 2025-07-13 14:02:27.814897 :B1757A5B: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-13 14:02:27.815185 :B1757A60: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-13 14:02:27.816021 :B1757A65:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:02:27.824823 :B1757A68:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:02:27.811 2025-07-13 14:02:27.824824 :B1757A69:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13572 pso_num=27 pso_serial#=191 2025-07-13 14:02:27.824968 :B1757A6A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13572 (legacy spawn) 2025-07-13 14:02:27.831526 :B1757A6B: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-13 14:02:27.831694 :B1757A74: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-13 14:02:27.832492 :B1757A75:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:02:29.626550 :B1757B14:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:02:29.618 2025-07-13 14:02:29.626552 :B1757B15:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11488 pso_num=27 pso_serial#=192 2025-07-13 14:02:29.626696 :B1757B16:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11488 (legacy spawn) 2025-07-13 14:02:29.633692 :B1757B17: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-13 14:02:29.633898 :B1757B1C: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-13 14:02:29.634825 :B1757B21:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:03:04.205165 :B175861D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:03:04.205 2025-07-13 14:03:04.205167 :B175861E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10408 pso_num=27 pso_serial#=193 2025-07-13 14:03:04.205306 :B175861F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10408 (legacy spawn) 2025-07-13 14:03:04.211721 :B1758620: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-13 14:03:04.211889 :B1758628: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-13 14:03:04.212686 :B175862A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:03:04.221416 :B175862D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:03:04.221 2025-07-13 14:03:04.221418 :B175862E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12004 pso_num=27 pso_serial#=194 2025-07-13 14:03:04.221562 :B175862F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12004 (legacy spawn) 2025-07-13 14:03:04.227999 :B1758630: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-13 14:03:04.228169 :B1758639: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-13 14:03:04.228990 :B175863A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:03:08.062799 :B17587EC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:03:08.054 2025-07-13 14:03:08.062801 :B17587ED:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14676 pso_num=27 pso_serial#=195 2025-07-13 14:03:08.062939 :B17587EE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14676 (legacy spawn) 2025-07-13 14:03:08.069407 :B17587EF: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-13 14:03:08.069576 :B17587F8: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-13 14:03:08.070375 :B17587F9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:03:24.863545 :B1758D42:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:03:24.863 2025-07-13 14:03:24.863547 :B1758D43:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17124 pso_num=27 pso_serial#=196 2025-07-13 14:03:24.863687 :B1758D44:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17124 osp_idx=25 osp_ver=159324 osp_pg=0 (spawn #381393) 2025-07-13 14:03:24.863688 :B1758D45: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-13 14:03:24.863688 :B1758D46:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=67 time=1096180751 2025-07-13 14:03:24.863688 :B1758D47:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 14:03:24.863689 :B1758D48:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 14:03:24.863689 :B1758D49:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 14:03:24.863690 :B1758D4A:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 14:03:24.863690 :B1758D4B:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 14:03:24.864207 :B1758D4C: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-13 14:03:24.864266 :B1758D52: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-13 14:03:24.868628 :B1758D64:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-13 14:03:24.868678 :B1758D65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-13 14:03:24.868958 :B1758D66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-13 14:03:24.868975 :B1758D67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-13 14:03:24.869206 :B1758D68:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-13 14:03:24.869220 :B1758D69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-13 14:03:24.869440 :B1758D6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-13 14:03:24.869454 :B1758D6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-13 14:03:24.869669 :B1758D6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-13 14:03:24.869682 :B1758D6D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-13 14:03:24.869911 :B1758D6E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-07-13 14:03:24.869926 :B1758D6F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-07-13 14:03:24.872428 :B1758D9D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-13 14:03:25.061074 :B17591B9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-13 14:03:25.055 2025-07-13 14:03:25.061075 :B17591BA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5116 pso_num=27 pso_serial#=197 2025-07-13 14:03:25.061203 :B17591BB:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=5116 osp_idx=25 osp_ver=159325 osp_pg=0 (spawn #381395) 2025-07-13 14:03:25.061204 :B17591BC: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-13 14:03:25.061205 :B17591BD:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=68 time=1096180954 2025-07-13 14:03:25.061205 :B17591BE:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 14:03:25.061206 :B17591BF:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 14:03:25.061206 :B17591C0:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 14:03:25.061207 :B17591C1:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 14:03:25.061207 :B17591C2:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 14:03:25.061710 :B17591CC: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: 5116, J002)