Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511140403\orcl_j002_12764_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:37468M/63366M, Ph+PgF:44824M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 12764, image: ORACLE.EXE (J002) *** 2025-05-11 14:04:03.903 *** SESSION ID:(225.15003) 2025-05-11 14:04:03.903 *** 2025-05-11 14:04:03.903 Process diagnostic dump for ORACLE.EXE (J002), OS id=12764, pid: 28, proc_ser: 113, sid: 225, sess_ser: 15003 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12764 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.438297 sec, exc=0.438297 sec, total=0.438297 sec wait times: max=0.500000 sec, heur=6.943275 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.000007 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.500169 sec, exc=0.500169 sec, total=0.500169 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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=9 seq_num=10 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.500123 sec, exc=0.500123 sec, total=0.500123 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.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.000010 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.511037 sec, exc=0.511037 sec, total=0.511037 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000008 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.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 Sampled Session History of session 225 serial 15003 --------------------------------------------------- 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, 14:03:56 - 14:04:03] idle wait at each sample [session created at: 14:03:56] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-11 14:04:03.903 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 12764, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-11 13:59:39.454330 :95ADE2D7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8604 (legacy spawn) 2025-05-11 13:59:39.460839 :95ADE2D8: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-05-11 13:59:39.460999 :95ADE2E1: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-05-11 13:59:39.461827 :95ADE2E2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:00:15.715721 :95ADF02E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:00:15.702 2025-05-11 14:00:15.715723 :95ADF02F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11936 pso_num=28 pso_serial#=87 2025-05-11 14:00:15.715856 :95ADF030:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11936 (legacy spawn) 2025-05-11 14:00:15.722306 :95ADF031: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-05-11 14:00:15.722494 :95ADF036: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-05-11 14:00:15.723286 :95ADF03B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:00:15.740595 :95ADF03E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:00:15.733 2025-05-11 14:00:15.740597 :95ADF03F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11268 pso_num=28 pso_serial#=88 2025-05-11 14:00:15.740769 :95ADF040:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11268 (legacy spawn) 2025-05-11 14:00:15.747187 :95ADF041: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-05-11 14:00:15.747359 :95ADF04A: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-05-11 14:00:15.748174 :95ADF04B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:00:26.630350 :95ADF3D1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:00:26.622 2025-05-11 14:00:26.630352 :95ADF3D2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12708 pso_num=28 pso_serial#=89 2025-05-11 14:00:26.630488 :95ADF3D3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12708 (legacy spawn) 2025-05-11 14:00:26.636912 :95ADF3D4: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-05-11 14:00:26.637086 :95ADF3D9: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-05-11 14:00:26.637887 :95ADF3DE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:00:30.761448 :95ADF4E1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:00:30.753 2025-05-11 14:00:30.761450 :95ADF4E2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12784 pso_num=28 pso_serial#=90 2025-05-11 14:00:30.761593 :95ADF4E3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12784 (legacy spawn) 2025-05-11 14:00:30.768157 :95ADF4E4: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-05-11 14:00:30.768318 :95ADF4E9: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-05-11 14:00:30.769120 :95ADF4EE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:00:57.680848 :95ADFE62:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:00:57.672 2025-05-11 14:00:57.680850 :95ADFE63:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12612 pso_num=28 pso_serial#=91 2025-05-11 14:00:57.681302 :95ADFE64:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12612 (legacy spawn) 2025-05-11 14:00:57.689723 :95ADFE86: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-05-11 14:00:57.689870 :95ADFE8F: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-05-11 14:00:57.690693 :95ADFE90:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:19.323966 :95AE05B1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:19.322 2025-05-11 14:01:19.323968 :95AE05B2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11488 pso_num=28 pso_serial#=92 2025-05-11 14:01:19.324116 :95AE05B3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11488 (legacy spawn) 2025-05-11 14:01:19.330768 :95AE05B4: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-05-11 14:01:19.330946 :95AE05B9: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-05-11 14:01:19.331804 :95AE05BE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:19.340762 :95AE05C1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:19.337 2025-05-11 14:01:19.340764 :95AE05C2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12464 pso_num=28 pso_serial#=93 2025-05-11 14:01:19.340914 :95AE05C3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12464 (legacy spawn) 2025-05-11 14:01:19.347598 :95AE05C4: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-05-11 14:01:19.347768 :95AE05CD: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-05-11 14:01:19.348634 :95AE05CE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:25.371209 :95AE079B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:25.362 2025-05-11 14:01:25.371211 :95AE079C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10284 pso_num=28 pso_serial#=94 2025-05-11 14:01:25.371352 :95AE079D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10284 (legacy spawn) 2025-05-11 14:01:25.377893 :95AE079E: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-05-11 14:01:25.378063 :95AE07A3: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-05-11 14:01:25.378898 :95AE07A8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:25.387538 :95AE07AB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:25.378 2025-05-11 14:01:25.387540 :95AE07AC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12376 pso_num=28 pso_serial#=95 2025-05-11 14:01:25.387720 :95AE07AD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12376 (legacy spawn) 2025-05-11 14:01:25.394141 :95AE07AE: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-05-11 14:01:25.394301 :95AE07B7: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-05-11 14:01:25.395154 :95AE07B8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:28.526145 :95AE08A4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:28.513 2025-05-11 14:01:28.526147 :95AE08A5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2752 pso_num=28 pso_serial#=96 2025-05-11 14:01:28.526333 :95AE08A6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2752 (legacy spawn) 2025-05-11 14:01:28.532765 :95AE08BA: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-05-11 14:01:28.532930 :95AE08C3: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-05-11 14:01:28.533758 :95AE08C4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:28.542313 :95AE08C7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:28.529 2025-05-11 14:01:28.542315 :95AE08C8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11580 pso_num=28 pso_serial#=97 2025-05-11 14:01:28.542459 :95AE08C9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11580 (legacy spawn) 2025-05-11 14:01:28.561496 :95AE08CA: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-05-11 14:01:28.561662 :95AE08D3: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-05-11 14:01:28.562500 :95AE08D4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:28.802215 :95AE08E3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:28.794 2025-05-11 14:01:28.802217 :95AE08E4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7872 pso_num=28 pso_serial#=98 2025-05-11 14:01:28.802358 :95AE08E5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7872 (legacy spawn) 2025-05-11 14:01:28.808706 :95AE08E6: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-05-11 14:01:28.808876 :95AE08EE: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-05-11 14:01:28.809687 :95AE08F0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:01:38.871274 :95AE0C66:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:01:38.862 2025-05-11 14:01:38.871277 :95AE0C67:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10372 pso_num=28 pso_serial#=99 2025-05-11 14:01:38.871435 :95AE0C68:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10372 (legacy spawn) 2025-05-11 14:01:38.877797 :95AE0C69: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-05-11 14:01:38.877965 :95AE0C72: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-05-11 14:01:38.878826 :95AE0C73:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:02:12.871864 :95AE1728:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:02:12.863 2025-05-11 14:02:12.871866 :95AE1729:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10952 pso_num=28 pso_serial#=100 2025-05-11 14:02:12.872008 :95AE172A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10952 (legacy spawn) 2025-05-11 14:02:12.878503 :95AE172B: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-05-11 14:02:12.878670 :95AE1730: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-05-11 14:02:12.879485 :95AE1735:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:02:31.023493 :95AE1CCA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:02:31.011 2025-05-11 14:02:31.023495 :95AE1CCB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12312 pso_num=28 pso_serial#=101 2025-05-11 14:02:31.023633 :95AE1CCC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12312 (legacy spawn) 2025-05-11 14:02:31.030124 :95AE1CCD: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-05-11 14:02:31.030291 :95AE1CD2: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-05-11 14:02:31.031093 :95AE1CD7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:02:37.925384 :95AE1EC5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:02:37.910 2025-05-11 14:02:37.925387 :95AE1EC6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12132 pso_num=28 pso_serial#=102 2025-05-11 14:02:37.925519 :95AE1EC7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12132 (legacy spawn) 2025-05-11 14:02:37.931945 :95AE1EC8: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-05-11 14:02:37.932120 :95AE1ED1: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-05-11 14:02:37.932964 :95AE1ED2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:02:37.941488 :95AE1ED5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:02:37.926 2025-05-11 14:02:37.941490 :95AE1ED6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13096 pso_num=28 pso_serial#=103 2025-05-11 14:02:37.941623 :95AE1ED7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13096 (legacy spawn) 2025-05-11 14:02:37.948089 :95AE1ED8: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-05-11 14:02:37.948249 :95AE1EE0: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-05-11 14:02:37.949010 :95AE1EE2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:02:41.027885 :95AE1FD4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:02:41.014 2025-05-11 14:02:41.027887 :95AE1FD5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13292 pso_num=28 pso_serial#=104 2025-05-11 14:02:41.028023 :95AE1FD6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13292 (legacy spawn) 2025-05-11 14:02:41.034466 :95AE2010: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-05-11 14:02:41.034632 :95AE2019: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-05-11 14:02:41.035402 :95AE201A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:02:41.044134 :95AE201D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:02:41.030 2025-05-11 14:02:41.044136 :95AE201E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12596 pso_num=28 pso_serial#=105 2025-05-11 14:02:41.044275 :95AE201F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12596 (legacy spawn) 2025-05-11 14:02:41.050755 :95AE2020: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-05-11 14:02:41.050919 :95AE2029: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-05-11 14:02:41.051736 :95AE202A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:14.149561 :95AE2AC8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:14.146 2025-05-11 14:03:14.149563 :95AE2AC9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12876 pso_num=28 pso_serial#=106 2025-05-11 14:03:14.149710 :95AE2ACA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12876 (legacy spawn) 2025-05-11 14:03:14.156459 :95AE2ACB: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-05-11 14:03:14.156630 :95AE2AD3: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-05-11 14:03:14.157526 :95AE2AD5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:14.166923 :95AE2AD8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:14.162 2025-05-11 14:03:14.166925 :95AE2AD9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10608 pso_num=28 pso_serial#=107 2025-05-11 14:03:14.167059 :95AE2ADA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10608 (legacy spawn) 2025-05-11 14:03:14.173603 :95AE2ADB: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-05-11 14:03:14.173775 :95AE2AE4: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-05-11 14:03:14.174636 :95AE2AE5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:17.420726 :95AE2C62:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:17.407 2025-05-11 14:03:17.420728 :95AE2C63:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8612 pso_num=28 pso_serial#=108 2025-05-11 14:03:17.420894 :95AE2C64:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8612 (legacy spawn) 2025-05-11 14:03:17.427479 :95AE2C65: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-05-11 14:03:17.427674 :95AE2C6A: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-05-11 14:03:17.428485 :95AE2C6F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:17.447048 :95AE2C72:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:17.438 2025-05-11 14:03:17.447050 :95AE2C73:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12544 pso_num=28 pso_serial#=109 2025-05-11 14:03:17.447197 :95AE2C74:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12544 (legacy spawn) 2025-05-11 14:03:17.453739 :95AE2C75: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-05-11 14:03:17.453901 :95AE2C7E: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-05-11 14:03:17.454679 :95AE2C7F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:21.156442 :95AE2DC9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:21.147 2025-05-11 14:03:21.156444 :95AE2DCA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12572 pso_num=28 pso_serial#=110 2025-05-11 14:03:21.156576 :95AE2DCB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12572 (legacy spawn) 2025-05-11 14:03:21.163077 :95AE2DCC: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-05-11 14:03:21.163244 :95AE2DD5: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-05-11 14:03:21.164049 :95AE2DD6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:33.526288 :95AE315B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:33.518 2025-05-11 14:03:33.526290 :95AE315C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11564 pso_num=28 pso_serial#=111 2025-05-11 14:03:33.526428 :95AE315D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11564 (legacy spawn) 2025-05-11 14:03:33.532824 :95AE315E: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-05-11 14:03:33.532992 :95AE3163: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-05-11 14:03:33.533800 :95AE3168:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:56.769781 :95AE3931:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:56.766 2025-05-11 14:03:56.769782 :95AE3932:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12644 pso_num=28 pso_serial#=112 2025-05-11 14:03:56.769925 :95AE3933:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12644 osp_idx=26 osp_ver=105681 osp_pg=0 (spawn #171349) 2025-05-11 14:03:56.769925 :95AE3934:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 14:03:56.769926 :95AE3935:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=131 time=4243013921 2025-05-11 14:03:56.769927 :95AE3936:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 14:03:56.769927 :95AE3937:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 14:03:56.769928 :95AE3938:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 14:03:56.769928 :95AE3939:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 14:03:56.769928 :95AE393A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 14:03:56.770539 :95AE393B: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-05-11 14:03:56.770583 :95AE3941: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-05-11 14:03:56.778851 :95AE3953:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-11 14:03:56.959872 :95AE3D5D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-11 14:03:56.954 2025-05-11 14:03:56.959874 :95AE3D5E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12764 pso_num=28 pso_serial#=113 2025-05-11 14:03:56.960025 :95AE3D5F:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12764 osp_idx=26 osp_ver=105682 osp_pg=0 (spawn #171352) 2025-05-11 14:03:56.960025 :95AE3D60:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 14:03:56.960026 :95AE3D61:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=132 time=4243014125 2025-05-11 14:03:56.960026 :95AE3D62:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 14:03:56.960027 :95AE3D63:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 14:03:56.960027 :95AE3D64:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 14:03:56.960028 :95AE3D65:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 14:03:56.960028 :95AE3D66:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 14:03:56.960634 :95AE3D70: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: 12764, J002)