Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250608140817\orcl_j003_9764_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:36808M/63366M, Ph+PgF:43437M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 9764, image: ORACLE.EXE (J003) *** 2025-06-08 14:08:17.112 *** SESSION ID:(233.24829) 2025-06-08 14:08:17.112 *** 2025-06-08 14:08:17.112 Process diagnostic dump for ORACLE.EXE (J003), OS id=9764, pid: 29, proc_ser: 143, sid: 233, sess_ser: 24829 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9764 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.478510 sec, exc=0.478510 sec, total=0.478510 sec wait times: max=0.500000 sec, heur=3.985425 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.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.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.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.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.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.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.503252 sec, exc=0.503252 sec, total=0.503252 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.510106 sec, exc=0.510106 sec, total=0.510106 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493503 sec, exc=0.493503 sec, total=0.493503 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 233 serial 24829 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [4 samples, 14:08:13 - 14:08:17] idle wait at each sample [session created at: 14:08:13] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-08 14:08:17.112 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 9764, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-08 06:00:13.219914 :A1D4C2C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.219957 :A1D4C2CE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:13.224498 :A1D4C2E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.248651 :A1D4C2E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.263621 :A1D4C2F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.291990 :A1D4C2F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.295828 :A1D4C301:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.299507 :A1D4C309:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.303753 :A1D4C311:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:13.304865 :A1D4C319:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.552454 :A1D4C360:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.565807*:A1D4C368:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DBFB518 estart=1749333614 eid=16779058 ctx=0x000000077392D068 cwh=0x0000000029950320 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-06-08 06:00:14.565807*:A1D4C369:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DBFB518 curCtx=0x000000002DBEFF30 pubTabIdxCnt=0 2025-06-08 06:00:14.565807*:A1D4C36A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DBFB518 2025-06-08 06:00:14.565807*:A1D4C36B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DBFB518 2025-06-08 06:00:14.565807*:A1D4C36C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DBFB518 curCtx=0x000000002DBEFF30 xsc->flg4=65792 flags=1 2025-06-08 06:00:14.565807*:A1D4C36D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DBFB518 2025-06-08 06:00:14.693586 :A1D4C36E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.693946 :A1D4C376:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.694222 :A1D4C37E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.694238 :A1D4C381:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.694297 :A1D4C38D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:14.696943 :A1D4C39B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:20.781061 :A1D4C5E3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:20.768 2025-06-08 06:00:20.781063 :A1D4C5E4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4856 pso_num=29 pso_serial#=126 2025-06-08 06:00:20.781197 :A1D4C5E5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4856 (legacy spawn) 2025-06-08 06:00:20.787938 :A1D4C5E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:20.788108 :A1D4C5EB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:20.788922 :A1D4C5F0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:26.989938 :A1D4C7D4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:26.976 2025-06-08 06:00:26.989940 :A1D4C7D5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15072 pso_num=29 pso_serial#=127 2025-06-08 06:00:26.990080 :A1D4C7D6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15072 (legacy spawn) 2025-06-08 06:00:26.996602 :A1D4C7D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:26.996795 :A1D4C7DC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:26.997588 :A1D4C7E1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:27.006167 :A1D4C7E4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:26.991 2025-06-08 06:00:27.006169 :A1D4C7E5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15300 pso_num=29 pso_serial#=128 2025-06-08 06:00:27.006350 :A1D4C7E6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15300 (legacy spawn) 2025-06-08 06:00:27.012716 :A1D4C7E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:27.012873 :A1D4C7F0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:27.013709 :A1D4C7F1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:28.515015 :A1D4C89B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:28.501 2025-06-08 06:00:28.515016 :A1D4C89C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9764 pso_num=29 pso_serial#=129 2025-06-08 06:00:28.515156 :A1D4C89D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9764 (legacy spawn) 2025-06-08 06:00:28.521678 :A1D4C89E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:28.521853 :A1D4C8A6:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:28.522659 :A1D4C8A8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:28.531286 :A1D4C8AB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:28.517 2025-06-08 06:00:28.531288 :A1D4C8AC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15548 pso_num=29 pso_serial#=130 2025-06-08 06:00:28.531428 :A1D4C8AD:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15548 (legacy spawn) 2025-06-08 06:00:28.537843 :A1D4C8AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:28.537871 :A1D4C8B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:28.538007 :A1D4C8B8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:28.538808 :A1D4C8C5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:29.045459 :A1D4C8F0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:29.036 2025-06-08 06:00:29.045461 :A1D4C8F1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14516 pso_num=29 pso_serial#=131 2025-06-08 06:00:29.045598 :A1D4C8F2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14516 (legacy spawn) 2025-06-08 06:00:29.051985 :A1D4C8F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:29.052148 :A1D4C8F8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:29.052936 :A1D4C8FD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:30.190368 :A1D4C923:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:30.177 2025-06-08 06:00:30.190370 :A1D4C924:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16084 pso_num=29 pso_serial#=132 2025-06-08 06:00:30.190507 :A1D4C925:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16084 (legacy spawn) 2025-06-08 06:00:30.196994 :A1D4C926:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:30.197162 :A1D4C92F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:30.197944 :A1D4C930:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 06:00:30.206898 :A1D4C933:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 06:00:30.192 2025-06-08 06:00:30.206900 :A1D4C934:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10020 pso_num=29 pso_serial#=133 2025-06-08 06:00:30.207032 :A1D4C935:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10020 (legacy spawn) 2025-06-08 06:00:30.213430 :A1D4C936:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:30.213597 :A1D4C93F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 06:00:30.214366 :A1D4C940:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 10:07:44.148829 :A1E7E2A0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 10:07:44.137 2025-06-08 10:07:44.148831 :A1E7E2A1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13420 pso_num=29 pso_serial#=134 2025-06-08 10:07:44.148956 :A1E7E2A2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13420 (legacy spawn) 2025-06-08 10:07:44.155453 :A1E7E2A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 10:07:44.155603 :A1E7E2AC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 10:07:44.156359 :A1E7E2AD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:00.644618 :A1F587C5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:00.639 2025-06-08 13:00:00.644619 :A1F587C6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15072 pso_num=29 pso_serial#=135 2025-06-08 13:00:00.644745 :A1F587C7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15072 (legacy spawn) 2025-06-08 13:00:00.650644 :A1F587C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:00.650745 :A1F587D1:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:00.651367 :A1F587D2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:04.762952 :A1F589FC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:04.758 2025-06-08 13:00:04.762953 :A1F589FD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12944 pso_num=29 pso_serial#=136 2025-06-08 13:00:04.763087 :A1F589FE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12944 osp_idx=27 osp_ver=12375 osp_pg=0 (spawn #264564) 2025-06-08 13:00:04.763088 :A1F589FF:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 13:00:04.763088 :A1F58A00:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=89 time=2363347954 2025-06-08 13:00:04.763088 :A1F58A01:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 13:00:04.763088 :A1F58A02:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 13:00:04.763089 :A1F58A03:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 13:00:04.763089 :A1F58A04:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 13:00:04.763089 :A1F58A05:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 13:00:04.763539 :A1F58A06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.763568 :A1F58A0D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:04.767698 :A1F58A1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.787758 :A1F58A27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.803335 :A1F58A2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.826257 :A1F58A37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.829408 :A1F58A3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.832712 :A1F58A47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.837539 :A1F58A4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.838508 :A1F58A57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.886399 :A1F58A5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:04.886408 :A1F58A60:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0004.021.0000c22a 2025-06-08 13:00:04.886745 :A1F58A68:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 4, ext 3, nex 4 2025-06-08 13:00:04.886750 :A1F58A69:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00814e1f where: ktspbwh1: ktspfsrch 2025-06-08 13:00:05.496961 :A1F58A98:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 4, ext 4, nex 5 2025-06-08 13:00:05.496967 :A1F58A99:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081569a where: kdiwh22: kdifind 2025-06-08 13:00:05.994913 :A1F58AA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:06.045043 :A1F58AD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:06.045374 :A1F58ADF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:06.045710 :A1F58AE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:06.045731 :A1F58AEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:06.045805 :A1F58AFA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:06.048739 :A1F58B04:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:13.719515 :A1F58EF5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:13.714 2025-06-08 13:00:13.719516 :A1F58EF6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13360 pso_num=29 pso_serial#=137 2025-06-08 13:00:13.719663 :A1F58EF7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13360 (legacy spawn) 2025-06-08 13:00:13.726030 :A1F58EF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:13.726170 :A1F58F01:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:13.726856 :A1F58F02:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:13.734451 :A1F58F05:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:13.730 2025-06-08 13:00:13.734452 :A1F58F06:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13080 pso_num=29 pso_serial#=138 2025-06-08 13:00:13.734586 :A1F58F07:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13080 (legacy spawn) 2025-06-08 13:00:13.740214 :A1F58F08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:13.740323 :A1F58F11:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:13.740938 :A1F58F12:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:17.025830 :A1F59021:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:17.016 2025-06-08 13:00:17.025832 :A1F59022:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16164 pso_num=29 pso_serial#=139 2025-06-08 13:00:17.025965 :A1F59023:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16164 (legacy spawn) 2025-06-08 13:00:17.031685 :A1F59024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:17.031819 :A1F5902D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:17.032456 :A1F5902E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:17.039979 :A1F59031:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:17.031 2025-06-08 13:00:17.039980 :A1F59032:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13924 pso_num=29 pso_serial#=140 2025-06-08 13:00:17.040111 :A1F59033:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13924 (legacy spawn) 2025-06-08 13:00:17.045632 :A1F59034:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:17.045760 :A1F5903D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:17.046364 :A1F5903E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 13:00:20.612004 :A1F5913B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 13:00:20.604 2025-06-08 13:00:20.612005 :A1F5913C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14968 pso_num=29 pso_serial#=141 2025-06-08 13:00:20.612141 :A1F5913D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14968 (legacy spawn) 2025-06-08 13:00:20.618179 :A1F5913E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 13:00:20.618310 :A1F59147:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 13:00:20.618952 :A1F59148:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 14:00:13.293480 :A1FA4BE7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 14:00:13.292 2025-06-08 14:00:13.293482 :A1FA4BE8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16324 pso_num=29 pso_serial#=142 2025-06-08 14:00:13.293620 :A1FA4BE9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16324 osp_idx=27 osp_ver=12381 osp_pg=0 (spawn #264704) 2025-06-08 14:00:13.293621 :A1FA4BEA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 14:00:13.293621 :A1FA4BEB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=90 time=2366956469 2025-06-08 14:00:13.293622 :A1FA4BEC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 14:00:13.293622 :A1FA4BED:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 14:00:13.293622 :A1FA4BEE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 14:00:13.293623 :A1FA4BEF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 14:00:13.293623 :A1FA4BF0:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 14:00:13.294129 :A1FA4BF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.294176 :A1FA4BF7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08 14:00:13.302065 :A1FA4C05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.303257 :A1FA4C0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.303854 :A1FA4C15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.304332 :A1FA4C1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.304766 :A1FA4C25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.307323 :A1FA4C2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.308503 :A1FA4C35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.359090 :A1FA4C3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.360568 :A1FA4C45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.361473 :A1FA4C4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.361712 :A1FA4C55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.361974 :A1FA4C5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 14:00:13.364029 :A1FA4C65:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-08 14:08:13.135653 :A1FAF58A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-08 14:08:13.130 2025-06-08 14:08:13.135655 :A1FAF58B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9764 pso_num=29 pso_serial#=143 2025-06-08 14:08:13.135777 :A1FAF58C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9764 osp_idx=27 osp_ver=12382 osp_pg=0 (spawn #264726) 2025-06-08 14:08:13.135778 :A1FAF58D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 14:08:13.135778 :A1FAF58E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=91 time=2367436329 2025-06-08 14:08:13.135778 :A1FAF58F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 14:08:13.135779 :A1FAF590:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 14:08:13.135779 :A1FAF591:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 14:08:13.135780 :A1FAF592:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 14:08:13.135780 :A1FAF593:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 14:08:13.136284 :A1FAF594:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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 29 (osid: 9764, J003)