Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802180619\orcl_j002_16580_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:35812M/63366M, Ph+PgF:41472M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 16580, image: ORACLE.EXE (J002) *** 2025-08-02 18:06:19.312 *** SESSION ID:(225.56697) 2025-08-02 18:06:19.312 *** 2025-08-02 18:06:19.312 Process diagnostic dump for ORACLE.EXE (J002), OS id=16580, pid: 28, proc_ser: 238, sid: 225, sess_ser: 56697 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16580 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.380922 sec, exc=0.380922 sec, total=0.380922 sec wait times: max=0.500000 sec, heur=4.404800 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.510094 sec, exc=0.510094 sec, total=0.510094 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: 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.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.503621 sec, exc=0.503621 sec, total=0.503621 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 225 serial 56697 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 18:06:14 - 18:06:19] idle wait at each sample [session created at: 18:06:14] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 18:06:19.312 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 16580, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 18:01:00.876791 :BA6D23E2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2648 (legacy spawn) 2025-08-02 18:01:00.883262 :BA6D23E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:01:00.883428 :BA6D23E8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:01:00.884266 :BA6D23ED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:01:05.055244 :BA6D2577:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:01:05.050 2025-08-02 18:01:05.055246 :BA6D2578:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19872 pso_num=28 pso_serial#=212 2025-08-02 18:01:05.055378 :BA6D2579:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=19872 osp_idx=26 osp_ver=192020 osp_pg=0 (spawn #448593) 2025-08-02 18:01:05.055379 :BA6D257A:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 18:01:05.055379 :BA6D257B:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=145 time=2838334361 2025-08-02 18:01:05.055380 :BA6D257C:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 18:01:05.055380 :BA6D257D:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 18:01:05.055380 :BA6D257E:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 18:01:05.055381 :BA6D257F:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 18:01:05.055381 :BA6D2580:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 18:01:05.055867 :BA6D2581:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-02 18:01:05.055910 :BA6D2587:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-02 18:01:05.075926 :BA6D2598:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:01:51.532114 :BA6D34DF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:01:51.518 2025-08-02 18:01:51.532116 :BA6D34E0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18492 pso_num=28 pso_serial#=213 2025-08-02 18:01:51.532253 :BA6D34E1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18492 (legacy spawn) 2025-08-02 18:01:51.538726 :BA6D34E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:01:51.538901 :BA6D34E7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:01:51.539683 :BA6D34EC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:02:03.107588 :BA6D3886:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:02:03.099 2025-08-02 18:02:03.107590 :BA6D3887:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13240 pso_num=28 pso_serial#=214 2025-08-02 18:02:03.107733 :BA6D3888:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13240 (legacy spawn) 2025-08-02 18:02:03.114272 :BA6D3889:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:03.114441 :BA6D388E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:02:03.115254 :BA6D3893:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:02:45.066684 :BA6D46A0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:02:45.064 2025-08-02 18:02:45.066686 :BA6D46A1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10368 pso_num=28 pso_serial#=215 2025-08-02 18:02:45.066832 :BA6D46A2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10368 (legacy spawn) 2025-08-02 18:02:45.073534 :BA6D46A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:45.073568 :BA6D46A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:45.073732 :BA6D46AA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:02:45.074590 :BA6D46BA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:02:45.083315 :BA6D46BD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:02:45.080 2025-08-02 18:02:45.083318 :BA6D46BE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18644 pso_num=28 pso_serial#=216 2025-08-02 18:02:45.083454 :BA6D46BF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18644 (legacy spawn) 2025-08-02 18:02:45.089994 :BA6D46C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:45.090167 :BA6D46C9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:02:45.091015 :BA6D46CA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:02:48.395446 :BA6D47D4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:02:48.381 2025-08-02 18:02:48.395449 :BA6D47D5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14924 pso_num=28 pso_serial#=217 2025-08-02 18:02:48.395606 :BA6D47D6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14924 (legacy spawn) 2025-08-02 18:02:48.402652 :BA6D47D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:48.402847 :BA6D47DC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:02:48.403733 :BA6D47E1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:02:48.413288 :BA6D47E4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:02:48.413 2025-08-02 18:02:48.413290 :BA6D47E5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15576 pso_num=28 pso_serial#=218 2025-08-02 18:02:48.413434 :BA6D47E6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15576 (legacy spawn) 2025-08-02 18:02:48.420110 :BA6D47E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:48.420280 :BA6D47EF:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:02:48.421191 :BA6D47F1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:02:59.711206 :BA6D4BB2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:02:59.702 2025-08-02 18:02:59.711208 :BA6D4BB3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17240 pso_num=28 pso_serial#=219 2025-08-02 18:02:59.711342 :BA6D4BB4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17240 (legacy spawn) 2025-08-02 18:02:59.717847 :BA6D4BB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:02:59.718012 :BA6D4BBE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:02:59.718796 :BA6D4BBF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:03:05.306511 :BA6D4D76:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:03:05.298 2025-08-02 18:03:05.306513 :BA6D4D77:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17920 pso_num=28 pso_serial#=220 2025-08-02 18:03:05.306647 :BA6D4D78:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17920 (legacy spawn) 2025-08-02 18:03:05.313120 :BA6D4D79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:03:05.313296 :BA6D4D7E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:03:05.314114 :BA6D4D83:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:03:42.097209 :BA6D5981:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:03:42.089 2025-08-02 18:03:42.097211 :BA6D5982:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14372 pso_num=28 pso_serial#=221 2025-08-02 18:03:42.097366 :BA6D5983:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14372 (legacy spawn) 2025-08-02 18:03:42.103775 :BA6D5984:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:03:42.103947 :BA6D598A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:03:42.104774 :BA6D598E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:03:57.886420 :BA6D5E94:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:03:57.881 2025-08-02 18:03:57.886422 :BA6D5E95:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19564 pso_num=28 pso_serial#=222 2025-08-02 18:03:57.886558 :BA6D5E96:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19564 (legacy spawn) 2025-08-02 18:03:57.901362 :BA6D5E97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:03:57.901542 :BA6D5E9C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:03:57.902370 :BA6D5EA1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:03:57.911213 :BA6D5EA4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:03:57.897 2025-08-02 18:03:57.911215 :BA6D5EA5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19904 pso_num=28 pso_serial#=223 2025-08-02 18:03:57.911359 :BA6D5EA6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19904 (legacy spawn) 2025-08-02 18:03:57.917976 :BA6D5EA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:03:57.918158 :BA6D5EB0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:03:57.918998 :BA6D5EB1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:00.977856 :BA6D5F96:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:00.964 2025-08-02 18:04:00.977858 :BA6D5F97:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19672 pso_num=28 pso_serial#=224 2025-08-02 18:04:00.977994 :BA6D5F98:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19672 (legacy spawn) 2025-08-02 18:04:00.984496 :BA6D5F99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:00.984689 :BA6D5F9E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:00.985482 :BA6D5FA3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:00.994173 :BA6D5FA6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:00.980 2025-08-02 18:04:00.994175 :BA6D5FA7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17496 pso_num=28 pso_serial#=225 2025-08-02 18:04:00.994316 :BA6D5FA8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17496 (legacy spawn) 2025-08-02 18:04:01.000856 :BA6D5FA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:01.001022 :BA6D5FB2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:01.001820 :BA6D5FB3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:07.471533 :BA6D6190:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:07.463 2025-08-02 18:04:07.471535 :BA6D6191:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7436 pso_num=28 pso_serial#=226 2025-08-02 18:04:07.471672 :BA6D6192:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7436 (legacy spawn) 2025-08-02 18:04:07.478057 :BA6D6193:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:07.478236 :BA6D6198:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:07.479071 :BA6D619D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:07.910121 :BA6D61AC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:07.901 2025-08-02 18:04:07.910123 :BA6D61AD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19912 pso_num=28 pso_serial#=227 2025-08-02 18:04:07.910255 :BA6D61AE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19912 (legacy spawn) 2025-08-02 18:04:07.916787 :BA6D61AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:07.916968 :BA6D61B8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:07.917816 :BA6D61B9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:37.308378 :BA6D6C54:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:37.294 2025-08-02 18:04:37.308380 :BA6D6C55:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19628 pso_num=28 pso_serial#=228 2025-08-02 18:04:37.308527 :BA6D6C56:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19628 (legacy spawn) 2025-08-02 18:04:37.315566 :BA6D6C57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:37.315742 :BA6D6C60:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:37.316641 :BA6D6C61:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:37.326225 :BA6D6C64:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:37.325 2025-08-02 18:04:37.326227 :BA6D6C65:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18948 pso_num=28 pso_serial#=229 2025-08-02 18:04:37.326371 :BA6D6C66:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18948 (legacy spawn) 2025-08-02 18:04:37.333399 :BA6D6C67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:37.333588 :BA6D6C6F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:37.334527 :BA6D6C71:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:38.562627 :BA6D6CFB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:38.554 2025-08-02 18:04:38.562629 :BA6D6CFC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19820 pso_num=28 pso_serial#=230 2025-08-02 18:04:38.562773 :BA6D6CFD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19820 (legacy spawn) 2025-08-02 18:04:38.569275 :BA6D6CFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:38.569448 :BA6D6D03:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:38.570264 :BA6D6D08:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:04:41.936907 :BA6D6E20:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:04:41.928 2025-08-02 18:04:41.936909 :BA6D6E21:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17856 pso_num=28 pso_serial#=231 2025-08-02 18:04:41.937039 :BA6D6E22:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17856 (legacy spawn) 2025-08-02 18:04:41.943634 :BA6D6E23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:04:41.943800 :BA6D6E28:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:04:41.944633 :BA6D6E2D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:05:40.699390 :BA6D84EF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:05:40.691 2025-08-02 18:05:40.699393 :BA6D84F0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4556 pso_num=28 pso_serial#=232 2025-08-02 18:05:40.699547 :BA6D84F1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=4556 (legacy spawn) 2025-08-02 18:05:40.706174 :BA6D84F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:05:40.706358 :BA6D84F7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:05:40.707219 :BA6D84FC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:05:47.181004 :BA6D8704:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:05:47.175 2025-08-02 18:05:47.181006 :BA6D8705:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20224 pso_num=28 pso_serial#=233 2025-08-02 18:05:47.181152 :BA6D8706:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20224 (legacy spawn) 2025-08-02 18:05:47.188103 :BA6D8707:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:05:47.188280 :BA6D8710:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:05:47.189200 :BA6D8711:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:05:47.198714 :BA6D8714:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:05:47.190 2025-08-02 18:05:47.198717 :BA6D8715:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19096 pso_num=28 pso_serial#=234 2025-08-02 18:05:47.198861 :BA6D8716:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19096 (legacy spawn) 2025-08-02 18:05:47.205751 :BA6D8717:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:05:47.205944 :BA6D8720:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:05:47.206845 :BA6D8721:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:05:49.943441 :BA6D87EB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:05:49.939 2025-08-02 18:05:49.943443 :BA6D87EC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19620 pso_num=28 pso_serial#=235 2025-08-02 18:05:49.943585 :BA6D87ED:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19620 (legacy spawn) 2025-08-02 18:05:49.956104 :BA6D87EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:05:49.956282 :BA6D87F3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:05:49.957117 :BA6D87F8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:05:50.264373 :BA6D881A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:05:50.252 2025-08-02 18:05:50.264375 :BA6D881B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14876 pso_num=28 pso_serial#=236 2025-08-02 18:05:50.264518 :BA6D881C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14876 (legacy spawn) 2025-08-02 18:05:50.271031 :BA6D881D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:05:50.271196 :BA6D8826:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:05:50.271984 :BA6D8827:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:05:50.290745 :BA6D8854:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:05:50.283 2025-08-02 18:05:50.290747 :BA6D8855:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13808 pso_num=28 pso_serial#=237 2025-08-02 18:05:50.290884 :BA6D8856:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13808 (legacy spawn) 2025-08-02 18:05:50.297293 :BA6D8857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-02 18:05:50.297493 :BA6D885E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 18:05:50.298445 :BA6D8861:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-02 18:06:14.911843 :BA6D97B9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-02 18:06:14.906 2025-08-02 18:06:14.911845 :BA6D97BA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16580 pso_num=28 pso_serial#=238 2025-08-02 18:06:14.911986 :BA6D97BB:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=16580 osp_idx=26 osp_ver=192046 osp_pg=0 (spawn #448610) 2025-08-02 18:06:14.911987 :BA6D97BC:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 18:06:14.911988 :BA6D97BD:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=146 time=2838644220 2025-08-02 18:06:14.911988 :BA6D97BE:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 18:06:14.911989 :BA6D97BF:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 18:06:14.911989 :BA6D97C0:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 18:06:14.911989 :BA6D97C1:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 18:06:14.911990 :BA6D97C2:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 18:06:14.912532 :BA6D97CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 16580, J002)