Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629100943\orcl_j002_5964_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:36533M/63366M, Ph+PgF:42623M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 5964, image: ORACLE.EXE (J002) *** 2025-06-29 10:09:43.374 *** SESSION ID:(219.33149) 2025-06-29 10:09:43.374 *** 2025-06-29 10:09:43.374 Process diagnostic dump for ORACLE.EXE (J002), OS id=5964, pid: 27, proc_ser: 40, sid: 219, sess_ser: 33149 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 5964 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.218618 sec, exc=0.218618 sec, total=0.218618 sec wait times: max=0.500000 sec, heur=6.743153 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.000015 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.509820 sec, exc=0.509820 sec, total=0.509820 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500012 sec, exc=0.500012 sec, total=0.500012 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500118 sec, exc=0.500118 sec, total=0.500118 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=8 seq_num=9 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.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.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510820 sec, exc=0.510820 sec, total=0.510820 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 219 serial 33149 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 10:09:36 - 10:09:43] idle wait at each sample [session created at: 10:09:36] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-06-29 10:09:43.390 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 5964, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 10:05:58.099120 :AB2ADE6F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:05:58.107768 :AB2ADE72:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:05:58.093 2025-06-29 10:05:58.107770 :AB2ADE73:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13824 pso_num=27 pso_serial#=16 2025-06-29 10:05:58.107908 :AB2ADE74:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13824 (legacy spawn) 2025-06-29 10:05:58.114378 :AB2ADE75: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-06-29 10:05:58.114560 :AB2ADE7E: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-06-29 10:05:58.115350 :AB2ADE7F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:06:01.645418 :AB2AE0DB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:06:01.634 2025-06-29 10:06:01.645420 :AB2AE0DC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14672 pso_num=27 pso_serial#=17 2025-06-29 10:06:01.645559 :AB2AE0DD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14672 (legacy spawn) 2025-06-29 10:06:01.652350 :AB2AE0DE: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-06-29 10:06:01.652538 :AB2AE0E3: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-06-29 10:06:01.653381 :AB2AE0E8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:06:01.662542 :AB2AE0EB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:06:01.650 2025-06-29 10:06:01.662544 :AB2AE0EC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15596 pso_num=27 pso_serial#=18 2025-06-29 10:06:01.662683 :AB2AE0ED:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15596 (legacy spawn) 2025-06-29 10:06:01.669527 :AB2AE0EE: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-06-29 10:06:01.669708 :AB2AE0F7: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-06-29 10:06:01.670605 :AB2AE0F8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:06:12.539586 :AB2AE45E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:06:12.526 2025-06-29 10:06:12.539588 :AB2AE45F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11636 pso_num=27 pso_serial#=19 2025-06-29 10:06:12.539722 :AB2AE460:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11636 (legacy spawn) 2025-06-29 10:06:12.546529 :AB2AE461: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-06-29 10:06:12.546718 :AB2AE46A: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-06-29 10:06:12.547611 :AB2AE46B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:06:21.198232 :AB2AE730:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:06:21.190 2025-06-29 10:06:21.198234 :AB2AE731:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14152 pso_num=27 pso_serial#=20 2025-06-29 10:06:21.198373 :AB2AE732:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14152 (legacy spawn) 2025-06-29 10:06:21.205153 :AB2AE733: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-06-29 10:06:21.205336 :AB2AE73A: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-06-29 10:06:21.206264 :AB2AE73D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:07:11.110724 :AB2AF7A8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:07:11.096 2025-06-29 10:07:11.110726 :AB2AF7A9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18256 pso_num=27 pso_serial#=21 2025-06-29 10:07:11.110870 :AB2AF7AA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18256 (legacy spawn) 2025-06-29 10:07:11.117377 :AB2AF7AB: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-06-29 10:07:11.117543 :AB2AF7B0: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-06-29 10:07:11.118387 :AB2AF7B5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:07:11.127135 :AB2AF7B8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:07:11.127 2025-06-29 10:07:11.127137 :AB2AF7B9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18212 pso_num=27 pso_serial#=22 2025-06-29 10:07:11.127312 :AB2AF7BA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18212 (legacy spawn) 2025-06-29 10:07:11.133854 :AB2AF7BB: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-06-29 10:07:11.134028 :AB2AF7C4: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-06-29 10:07:11.134840 :AB2AF7C5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:07:14.291505 :AB2AF8C7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:07:14.278 2025-06-29 10:07:14.291508 :AB2AF8C8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14636 pso_num=27 pso_serial#=23 2025-06-29 10:07:14.291701 :AB2AF8C9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14636 (legacy spawn) 2025-06-29 10:07:14.298701 :AB2AF8CA: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-06-29 10:07:14.298882 :AB2AF8CF: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-06-29 10:07:14.299796 :AB2AF8D4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:07:14.309127 :AB2AF8D7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:07:14.309 2025-06-29 10:07:14.309129 :AB2AF8D8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2812 pso_num=27 pso_serial#=24 2025-06-29 10:07:14.309279 :AB2AF8D9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2812 (legacy spawn) 2025-06-29 10:07:14.315902 :AB2AF8DA: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-06-29 10:07:14.316064 :AB2AF8E3: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-06-29 10:07:14.316941 :AB2AF8E4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:07:21.639489 :AB2AFB1A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:07:21.631 2025-06-29 10:07:21.639491 :AB2AFB1B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16692 pso_num=27 pso_serial#=25 2025-06-29 10:07:21.639623 :AB2AFB1C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16692 (legacy spawn) 2025-06-29 10:07:21.646083 :AB2AFB1D: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-06-29 10:07:21.646272 :AB2AFB26: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-06-29 10:07:21.647058 :AB2AFB27:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:07:23.357847 :AB2AFBC3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:07:23.350 2025-06-29 10:07:23.357849 :AB2AFBC4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14356 pso_num=27 pso_serial#=26 2025-06-29 10:07:23.357988 :AB2AFBC5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14356 (legacy spawn) 2025-06-29 10:07:23.364548 :AB2AFBC6: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-06-29 10:07:23.364730 :AB2AFBCB: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-06-29 10:07:23.365560 :AB2AFBD0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:08:23.695995 :AB2B0F6A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:08:23.692 2025-06-29 10:08:23.695997 :AB2B0F6B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11332 pso_num=27 pso_serial#=27 2025-06-29 10:08:23.696149 :AB2B0F6C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11332 (legacy spawn) 2025-06-29 10:08:23.702961 :AB2B0F6D: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-06-29 10:08:23.703157 :AB2B0F72: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-06-29 10:08:23.704062 :AB2B0F77:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:08:23.713423 :AB2B0F7A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:08:23.708 2025-06-29 10:08:23.713425 :AB2B0F7B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4424 pso_num=27 pso_serial#=28 2025-06-29 10:08:23.713565 :AB2B0F7C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4424 (legacy spawn) 2025-06-29 10:08:23.720584 :AB2B0F7D: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-06-29 10:08:23.720766 :AB2B0F86: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-06-29 10:08:23.721705 :AB2B0F87:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:08:25.592185 :AB2B1030:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:08:25.583 2025-06-29 10:08:25.592187 :AB2B1031:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4284 pso_num=27 pso_serial#=29 2025-06-29 10:08:25.592335 :AB2B1032:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4284 (legacy spawn) 2025-06-29 10:08:25.598862 :AB2B1033: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-06-29 10:08:25.599037 :AB2B1038: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-06-29 10:08:25.599870 :AB2B103D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:08:56.666585 :AB2B1AA2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:08:56.659 2025-06-29 10:08:56.666587 :AB2B1AA3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9912 pso_num=27 pso_serial#=30 2025-06-29 10:08:56.666727 :AB2B1AA4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9912 (legacy spawn) 2025-06-29 10:08:56.673156 :AB2B1AA5: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-06-29 10:08:56.673321 :AB2B1AAE: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-06-29 10:08:56.674144 :AB2B1AAF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:00.005971 :AB2B1BAA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:08:59.991 2025-06-29 10:09:00.005974 :AB2B1BAB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16440 pso_num=27 pso_serial#=31 2025-06-29 10:09:00.006109 :AB2B1BAC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16440 (legacy spawn) 2025-06-29 10:09:00.012524 :AB2B1BAD: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-06-29 10:09:00.012699 :AB2B1BB2: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-06-29 10:09:00.013475 :AB2B1BB7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:00.031604 :AB2B1BBA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:00.023 2025-06-29 10:09:00.031607 :AB2B1BBB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13928 pso_num=27 pso_serial#=32 2025-06-29 10:09:00.031738 :AB2B1BBC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13928 (legacy spawn) 2025-06-29 10:09:00.038099 :AB2B1BBD: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-06-29 10:09:00.038290 :AB2B1BC6: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-06-29 10:09:00.039094 :AB2B1BC7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:03.093096 :AB2B1CB9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:03.080 2025-06-29 10:09:03.093098 :AB2B1CBA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13012 pso_num=27 pso_serial#=33 2025-06-29 10:09:03.093238 :AB2B1CBB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13012 (legacy spawn) 2025-06-29 10:09:03.099754 :AB2B1CBC: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-06-29 10:09:03.099944 :AB2B1CC1: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-06-29 10:09:03.100755 :AB2B1CC6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:03.109331 :AB2B1CC9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:03.096 2025-06-29 10:09:03.109334 :AB2B1CCA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4988 pso_num=27 pso_serial#=34 2025-06-29 10:09:03.109465 :AB2B1CCB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4988 (legacy spawn) 2025-06-29 10:09:03.115959 :AB2B1CCC: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-06-29 10:09:03.116130 :AB2B1CD5: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-06-29 10:09:03.116929 :AB2B1CD6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:03.951470 :AB2B1D26:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:03.939 2025-06-29 10:09:03.951472 :AB2B1D27:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8952 pso_num=27 pso_serial#=35 2025-06-29 10:09:03.951606 :AB2B1D28:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8952 (legacy spawn) 2025-06-29 10:09:03.958114 :AB2B1D29: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-06-29 10:09:03.958289 :AB2B1D2E: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-06-29 10:09:03.959111 :AB2B1D33:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:27.784207 :AB2B24C2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:27.772 2025-06-29 10:09:27.784209 :AB2B24C3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15484 pso_num=27 pso_serial#=36 2025-06-29 10:09:27.784345 :AB2B24C4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15484 (legacy spawn) 2025-06-29 10:09:27.790791 :AB2B24C5: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-06-29 10:09:27.790822 :AB2B24C8: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-06-29 10:09:27.790991 :AB2B24CF: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-06-29 10:09:27.791792 :AB2B24DC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:36.295594 :AB2B2755:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:36.281 2025-06-29 10:09:36.295596 :AB2B2756:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15412 pso_num=27 pso_serial#=37 2025-06-29 10:09:36.295736 :AB2B2757:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15412 (legacy spawn) 2025-06-29 10:09:36.302720 :AB2B2758: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-06-29 10:09:36.302903 :AB2B275D: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-06-29 10:09:36.303717 :AB2B2762:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:36.312515 :AB2B2765:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:36.312 2025-06-29 10:09:36.312517 :AB2B2766:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16580 pso_num=27 pso_serial#=38 2025-06-29 10:09:36.312653 :AB2B2767:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16580 (legacy spawn) 2025-06-29 10:09:36.319287 :AB2B2768: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-06-29 10:09:36.319449 :AB2B2771: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-06-29 10:09:36.320328 :AB2B2772:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:36.442597 :AB2B27A3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:36.437 2025-06-29 10:09:36.442599 :AB2B27A4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10600 pso_num=27 pso_serial#=39 2025-06-29 10:09:36.442739 :AB2B27A5:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10600 osp_idx=25 osp_ver=114797 osp_pg=0 (spawn #334206) 2025-06-29 10:09:36.442740 :AB2B27A6:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:09:36.442740 :AB2B27A7:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=35 time=4167519625 2025-06-29 10:09:36.442741 :AB2B27A8:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:09:36.442741 :AB2B27A9:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:09:36.442742 :AB2B27AA:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:09:36.442742 :AB2B27AB:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:09:36.442742 :AB2B27AC:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:09:36.443232 :AB2B27AD: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-06-29 10:09:36.443278 :AB2B27B3: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-06-29 10:09:36.447467 :AB2B27CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-29 10:09:36.447516 :AB2B27CB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-29 10:09:36.447771 :AB2B27CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-29 10:09:36.447788 :AB2B27CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-29 10:09:36.448007 :AB2B27CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-29 10:09:36.448020 :AB2B27CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-29 10:09:36.448232 :AB2B27D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-29 10:09:36.448245 :AB2B27D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-29 10:09:36.448449 :AB2B27D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-29 10:09:36.448461 :AB2B27D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-29 10:09:36.448680 :AB2B27D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-29 10:09:36.448701 :AB2B27D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-29 10:09:36.451257 :AB2B2808:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-29 10:09:36.645887 :AB2B2C3F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-29 10:09:36.640 2025-06-29 10:09:36.645888 :AB2B2C40:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5964 pso_num=27 pso_serial#=40 2025-06-29 10:09:36.646022 :AB2B2C41:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=5964 osp_idx=25 osp_ver=114798 osp_pg=0 (spawn #334208) 2025-06-29 10:09:36.646023 :AB2B2C42:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:09:36.646024 :AB2B2C43:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=36 time=4167519829 2025-06-29 10:09:36.646025 :AB2B2C44:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:09:36.646025 :AB2B2C45:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:09:36.646025 :AB2B2C46:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:09:36.646026 :AB2B2C47:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:09:36.646026 :AB2B2C48:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:09:36.646519 :AB2B2C52: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: 5964, J002)