Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816180819\orcl_j002_19324_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:35448M/63366M, Ph+PgF:40795M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 19324, image: ORACLE.EXE (J002) *** 2025-08-16 18:08:19.687 *** SESSION ID:(225.9485) 2025-08-16 18:08:19.687 *** 2025-08-16 18:08:19.687 Process diagnostic dump for ORACLE.EXE (J002), OS id=19324, pid: 28, proc_ser: 60, sid: 225, sess_ser: 9485 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19324 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=0.160731 sec, exc=0.160731 sec, total=0.160731 sec wait times: max=0.500000 sec, heur=9.194885 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 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=14 seq_num=15 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.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499967 sec, exc=0.499967 sec, total=0.499967 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500014 sec, exc=0.500014 sec, total=0.500014 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510095 sec, exc=0.510095 sec, total=0.510095 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510078 sec, exc=0.510078 sec, total=0.510078 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time Sampled Session History of session 225 serial 9485 --------------------------------------------------- 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 --------------------------------------------------- [10 samples, 18:08:10 - 18:08:19] idle wait at each sample [session created at: 18:08:10] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 18:08:19.687 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 19324, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 18:03:55.716837 :C097EC71: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-16 18:03:55.717640 :C097EC72:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:04:07.420257 :C097F010:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:04:07.412 2025-08-16 18:04:07.420259 :C097F011:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19536 pso_num=28 pso_serial#=36 2025-08-16 18:04:07.420393 :C097F012:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19536 (legacy spawn) 2025-08-16 18:04:07.426858 :C097F013: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-16 18:04:07.427031 :C097F018: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-16 18:04:07.427941 :C097F01D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:00.471327 :C098014F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:00.456 2025-08-16 18:05:00.471329 :C0980150:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21324 pso_num=28 pso_serial#=37 2025-08-16 18:05:00.471468 :C0980151:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21324 (legacy spawn) 2025-08-16 18:05:00.477966 :C0980152: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-16 18:05:00.478158 :C098015B: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-16 18:05:00.478958 :C098015C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:00.487633 :C098015F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:00.487 2025-08-16 18:05:00.487635 :C0980160:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19000 pso_num=28 pso_serial#=38 2025-08-16 18:05:00.487771 :C0980161:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19000 (legacy spawn) 2025-08-16 18:05:00.494214 :C0980162: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-16 18:05:00.494374 :C098016B: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-16 18:05:00.495155 :C098016C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:03.545382 :C098025B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:03.532 2025-08-16 18:05:03.545384 :C098025C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19336 pso_num=28 pso_serial#=39 2025-08-16 18:05:03.545525 :C098025D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19336 (legacy spawn) 2025-08-16 18:05:03.551998 :C098025E: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-16 18:05:03.552171 :C0980264: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-16 18:05:03.552956 :C0980268:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:03.561648 :C098026B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:03.547 2025-08-16 18:05:03.561650 :C098026C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21728 pso_num=28 pso_serial#=40 2025-08-16 18:05:03.561789 :C098026D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21728 (legacy spawn) 2025-08-16 18:05:03.568294 :C098026E: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-16 18:05:03.568460 :C0980277: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-16 18:05:03.569292 :C0980278:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:04.213080 :C09802B7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:04.204 2025-08-16 18:05:04.213082 :C09802B8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12948 pso_num=28 pso_serial#=41 2025-08-16 18:05:04.213219 :C09802B9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12948 (legacy spawn) 2025-08-16 18:05:04.219704 :C09802BA: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-16 18:05:04.219878 :C09802BF: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-16 18:05:04.220693 :C09802C4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:09.648377 :C0980466:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:09.640 2025-08-16 18:05:09.648379 :C0980467:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21764 pso_num=28 pso_serial#=42 2025-08-16 18:05:09.648516 :C0980468:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21764 (legacy spawn) 2025-08-16 18:05:09.655084 :C0980469: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-16 18:05:09.655288 :C098046E: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-16 18:05:09.656209 :C0980473:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:05:59.975896 :C0981B99:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:05:59.967 2025-08-16 18:05:59.975898 :C0981B9A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22236 pso_num=28 pso_serial#=43 2025-08-16 18:05:59.976045 :C0981B9B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22236 (legacy spawn) 2025-08-16 18:05:59.982469 :C0981B9C: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-16 18:05:59.982653 :C0981BA1: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-16 18:05:59.983494 :C0981BA6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:11.851266 :C0981F6B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:11.843 2025-08-16 18:06:11.851269 :C0981F6C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20996 pso_num=28 pso_serial#=44 2025-08-16 18:06:11.851413 :C0981F6D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20996 (legacy spawn) 2025-08-16 18:06:11.875846 :C0981F6E: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-16 18:06:11.876037 :C0981F77: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-16 18:06:11.877049 :C0981F78:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:12.180858 :C0981F92:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:12.171 2025-08-16 18:06:12.180860 :C0981F93:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20296 pso_num=28 pso_serial#=45 2025-08-16 18:06:12.181002 :C0981F94:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20296 (legacy spawn) 2025-08-16 18:06:12.188016 :C0981F95: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-16 18:06:12.188205 :C0981F9E: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-16 18:06:12.189101 :C0981F9F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:12.982100 :C0981FAD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:12.968 2025-08-16 18:06:12.982102 :C0981FAE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7692 pso_num=28 pso_serial#=46 2025-08-16 18:06:12.982245 :C0981FAF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7692 (legacy spawn) 2025-08-16 18:06:12.988757 :C0981FB0: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-16 18:06:12.988934 :C0981FB5: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-16 18:06:12.989750 :C0981FBA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:12.998367 :C0981FBD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:12.984 2025-08-16 18:06:12.998370 :C0981FBE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19848 pso_num=28 pso_serial#=47 2025-08-16 18:06:12.998508 :C0981FBF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19848 (legacy spawn) 2025-08-16 18:06:13.005035 :C0981FC0: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-16 18:06:13.005201 :C0981FC9: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-16 18:06:13.006006 :C0981FCA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:16.070744 :C09820E5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:16.057 2025-08-16 18:06:16.070746 :C09820E6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21104 pso_num=28 pso_serial#=48 2025-08-16 18:06:16.070880 :C09820E7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21104 (legacy spawn) 2025-08-16 18:06:16.077495 :C09820F4: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-16 18:06:16.077678 :C09820F9: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-16 18:06:16.078475 :C09820FE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:16.087242 :C0982101:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:16.073 2025-08-16 18:06:16.087245 :C0982102:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17340 pso_num=28 pso_serial#=49 2025-08-16 18:06:16.087384 :C0982103:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17340 (legacy spawn) 2025-08-16 18:06:16.093881 :C0982104: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-16 18:06:16.094072 :C098210D: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-16 18:06:16.094898 :C098210E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:23.113448 :C09822FB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:23.111 2025-08-16 18:06:23.113450 :C09822FC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4140 pso_num=28 pso_serial#=50 2025-08-16 18:06:23.113619 :C09822FD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=4140 (legacy spawn) 2025-08-16 18:06:23.120091 :C09822FE: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-16 18:06:23.120264 :C0982307: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-16 18:06:23.121087 :C0982308:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:06:23.129856 :C098230B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:06:23.127 2025-08-16 18:06:23.129858 :C098230C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22256 pso_num=28 pso_serial#=51 2025-08-16 18:06:23.129998 :C098230D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22256 (legacy spawn) 2025-08-16 18:06:23.136520 :C098230E: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-16 18:06:23.136684 :C0982317: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-16 18:06:23.137495 :C0982318:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:07:14.054104 :C098334E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:07:14.046 2025-08-16 18:07:14.054106 :C098334F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20928 pso_num=28 pso_serial#=52 2025-08-16 18:07:14.054251 :C0983350:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20928 (legacy spawn) 2025-08-16 18:07:14.060760 :C0983351: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-16 18:07:14.060938 :C0983356: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-16 18:07:14.061774 :C098335B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:07:20.423990 :C0983547:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:07:20.415 2025-08-16 18:07:20.423992 :C0983548:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22004 pso_num=28 pso_serial#=53 2025-08-16 18:07:20.424132 :C0983549:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22004 (legacy spawn) 2025-08-16 18:07:20.430649 :C098354A: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-16 18:07:20.430837 :C0983553: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-16 18:07:20.431635 :C0983554:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:07:55.850303 :C098413D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:07:55.841 2025-08-16 18:07:55.850305 :C098413E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22480 pso_num=28 pso_serial#=54 2025-08-16 18:07:55.850448 :C098413F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22480 (legacy spawn) 2025-08-16 18:07:55.857481 :C0984140: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-16 18:07:55.857665 :C0984145: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-16 18:07:55.858614 :C098414A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:08:02.386103 :C0984346:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:08:02.372 2025-08-16 18:08:02.386105 :C0984347:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22284 pso_num=28 pso_serial#=55 2025-08-16 18:08:02.386243 :C0984348:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22284 (legacy spawn) 2025-08-16 18:08:02.392750 :C0984349: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-16 18:08:02.392925 :C098434E: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-16 18:08:02.393710 :C0984353:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:08:02.402400 :C0984356:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:08:02.387 2025-08-16 18:08:02.402402 :C0984357:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22512 pso_num=28 pso_serial#=56 2025-08-16 18:08:02.402548 :C0984358:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22512 (legacy spawn) 2025-08-16 18:08:02.408993 :C0984359: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-16 18:08:02.409171 :C0984362: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-16 18:08:02.409981 :C0984363:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:08:05.482989 :C0984463:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:08:05.470 2025-08-16 18:08:05.482991 :C0984464:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19908 pso_num=28 pso_serial#=57 2025-08-16 18:08:05.483131 :C0984465:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19908 (legacy spawn) 2025-08-16 18:08:05.502528 :C0984466: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-16 18:08:05.502711 :C098446B: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-16 18:08:05.503613 :C0984470:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:08:05.512318 :C0984473:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:08:05.501 2025-08-16 18:08:05.512320 :C0984474:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19060 pso_num=28 pso_serial#=58 2025-08-16 18:08:05.512457 :C0984475:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19060 (legacy spawn) 2025-08-16 18:08:05.518969 :C0984476: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-16 18:08:05.519133 :C098447E: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-16 18:08:05.519919 :C0984480:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:08:10.292383 :C0984633:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:08:10.287 2025-08-16 18:08:10.292385 :C0984634:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16800 pso_num=28 pso_serial#=59 2025-08-16 18:08:10.292524 :C0984635:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=16800 osp_idx=26 osp_ver=233942 osp_pg=0 (spawn #495280) 2025-08-16 18:08:10.292525 :C0984636: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-16 18:08:10.292525 :C0984637:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=119 time=4048359595 2025-08-16 18:08:10.292525 :C0984638:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 18:08:10.292526 :C0984639:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 18:08:10.292526 :C098463A:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 18:08:10.292527 :C098463B:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 18:08:10.292527 :C098463C:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 18:08:10.293030 :C098463D: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-16 18:08:10.293072 :C0984643: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-16 18:08:10.297215 :C0984655:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 18:08:10.297263 :C0984656:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 18:08:10.297517 :C0984657:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 18:08:10.297533 :C0984658:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 18:08:10.297750 :C0984659:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 18:08:10.297763 :C098465A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 18:08:10.297970 :C098465B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 18:08:10.297983 :C098465C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 18:08:10.298183 :C098465D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 18:08:10.298196 :C098465E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 18:08:10.298403 :C098465F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-16 18:08:10.298416 :C0984660:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-16 18:08:10.300958 :C098468E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-16 18:08:10.495759 :C0984848:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-16 18:08:10.490 2025-08-16 18:08:10.495761 :C0984849:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19324 pso_num=28 pso_serial#=60 2025-08-16 18:08:10.495905 :C098484A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=19324 osp_idx=26 osp_ver=233943 osp_pg=0 (spawn #495282) 2025-08-16 18:08:10.495906 :C098484B: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-16 18:08:10.495906 :C098484C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=120 time=4048359798 2025-08-16 18:08:10.495907 :C098484D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 18:08:10.495907 :C098484E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 18:08:10.495907 :C098484F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 18:08:10.495908 :C0984850:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 18:08:10.495908 :C0984851:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 18:08:10.496431 :C098485B: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: 19324, J002)