Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250628220813\orcl_j002_9288_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:36381M/63366M, Ph+PgF:42520M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 9288, image: ORACLE.EXE (J002) *** 2025-06-28 22:08:13.316 *** SESSION ID:(219.27299) 2025-06-28 22:08:13.316 *** 2025-06-28 22:08:13.316 Process diagnostic dump for ORACLE.EXE (J002), OS id=9288, pid: 27, proc_ser: 175, sid: 219, sess_ser: 27299 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9288 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.375208 sec, exc=0.375208 sec, total=0.375208 sec wait times: max=0.500000 sec, heur=7.389315 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=13 seq_num=14 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500114 sec, exc=0.500114 sec, total=0.500114 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=10 seq_num=11 snap_id=1 wait times: snap=0.499979 sec, exc=0.499979 sec, total=0.499979 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500014 sec, exc=0.500014 sec, total=0.500014 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.508707 sec, exc=0.508707 sec, total=0.508707 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500160 sec, exc=0.500160 sec, total=0.500160 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=4 seq_num=5 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 219 serial 27299 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:08:05 - 22:08:13] idle wait at each sample [session created at: 22:08:05] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-28 22:08:13.316 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 9288, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-28 22:03:00.584868 :AAF18EDE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:03:05.099675 :AAF19021:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:03:05.094 2025-06-28 22:03:05.099677 :AAF19022:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7564 pso_num=27 pso_serial#=152 2025-06-28 22:03:05.099813 :AAF19023:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=7564 osp_idx=25 osp_ver=111850 osp_pg=0 (spawn #332516) 2025-06-28 22:03:05.099814 :AAF19024: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-28 22:03:05.099814 :AAF19025:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=212 time=4123928282 2025-06-28 22:03:05.099815 :AAF19026:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 22:03:05.099815 :AAF19027:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 22:03:05.099815 :AAF19028:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 22:03:05.099816 :AAF19029:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 22:03:05.099816 :AAF1902A:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 22:03:05.100304 :AAF1902B: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-28 22:03:05.100349 :AAF19031: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-28 22:03:05.108617 :AAF19043:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:03:58.538797 :AAF1A19B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:03:58.535 2025-06-28 22:03:58.538800 :AAF1A19C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12708 pso_num=27 pso_serial#=153 2025-06-28 22:03:58.538945 :AAF1A19D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12708 (legacy spawn) 2025-06-28 22:03:58.545499 :AAF1A19E: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-28 22:03:58.545688 :AAF1A1A6: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-28 22:03:58.546524 :AAF1A1A8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:03:58.555680 :AAF1A1AB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:03:58.551 2025-06-28 22:03:58.555682 :AAF1A1AC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6572 pso_num=27 pso_serial#=154 2025-06-28 22:03:58.555825 :AAF1A1AD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6572 (legacy spawn) 2025-06-28 22:03:58.562789 :AAF1A1AE: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-28 22:03:58.562958 :AAF1A1B7: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-28 22:03:58.563829 :AAF1A1B8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:04:01.603400 :AAF1A2B4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:04:01.592 2025-06-28 22:04:01.603402 :AAF1A2B5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17820 pso_num=27 pso_serial#=155 2025-06-28 22:04:01.603560 :AAF1A2B6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17820 (legacy spawn) 2025-06-28 22:04:01.610403 :AAF1A2B7: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-28 22:04:01.610579 :AAF1A2BC: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-28 22:04:01.611460 :AAF1A2C1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:04:01.620533 :AAF1A2C4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:04:01.608 2025-06-28 22:04:01.620536 :AAF1A2C5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11660 pso_num=27 pso_serial#=156 2025-06-28 22:04:01.620678 :AAF1A2C6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11660 (legacy spawn) 2025-06-28 22:04:01.627759 :AAF1A2C7: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-28 22:04:01.627984 :AAF1A2CF: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-28 22:04:01.628876 :AAF1A2D1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:04:08.736974 :AAF1A517:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:04:08.728 2025-06-28 22:04:08.736977 :AAF1A518:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17832 pso_num=27 pso_serial#=157 2025-06-28 22:04:08.737110 :AAF1A519:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17832 (legacy spawn) 2025-06-28 22:04:08.743527 :AAF1A51A: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-28 22:04:08.743693 :AAF1A51F: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-28 22:04:08.744519 :AAF1A524:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:04:56.679041 :AAF1B4C7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:04:56.666 2025-06-28 22:04:56.679043 :AAF1B4C8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10888 pso_num=27 pso_serial#=158 2025-06-28 22:04:56.679184 :AAF1B4C9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10888 (legacy spawn) 2025-06-28 22:04:56.699627 :AAF1B4CA: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-28 22:04:56.699823 :AAF1B4CF: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-28 22:04:56.700791 :AAF1B4D4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:05:11.195150 :AAF1BEE8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:05:11.180 2025-06-28 22:05:11.195152 :AAF1BEE9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4200 pso_num=27 pso_serial#=159 2025-06-28 22:05:11.195280 :AAF1BEEA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4200 (legacy spawn) 2025-06-28 22:05:11.201696 :AAF1BEEB: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-28 22:05:11.201869 :AAF1BEF0: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-28 22:05:11.202653 :AAF1BEF5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:05:11.211257 :AAF1BEF8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:05:11.196 2025-06-28 22:05:11.211259 :AAF1BEF9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6600 pso_num=27 pso_serial#=160 2025-06-28 22:05:11.211392 :AAF1BEFA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6600 (legacy spawn) 2025-06-28 22:05:11.217848 :AAF1BEFB: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-28 22:05:11.218011 :AAF1BF04: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-28 22:05:11.218801 :AAF1BF05:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:05:14.404057 :AAF1C022:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:05:14.393 2025-06-28 22:05:14.404059 :AAF1C023:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18072 pso_num=27 pso_serial#=161 2025-06-28 22:05:14.404201 :AAF1C024:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18072 (legacy spawn) 2025-06-28 22:05:14.410700 :AAF1C025: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-28 22:05:14.410904 :AAF1C02A: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-28 22:05:14.411706 :AAF1C02F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:05:14.420340 :AAF1C032:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:05:14.409 2025-06-28 22:05:14.420342 :AAF1C033:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13792 pso_num=27 pso_serial#=162 2025-06-28 22:05:14.420488 :AAF1C034:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13792 (legacy spawn) 2025-06-28 22:05:14.426961 :AAF1C035: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-28 22:05:14.427117 :AAF1C03E: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-28 22:05:14.427930 :AAF1C03F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:05:16.970616 :AAF1C104:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:05:16.961 2025-06-28 22:05:16.970618 :AAF1C105:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17996 pso_num=27 pso_serial#=163 2025-06-28 22:05:16.970755 :AAF1C106:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17996 (legacy spawn) 2025-06-28 22:05:16.977168 :AAF1C107: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-28 22:05:16.977334 :AAF1C10C: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-28 22:05:16.978133 :AAF1C111:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:05:58.921678 :AAF1D0A3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:05:58.908 2025-06-28 22:05:58.921680 :AAF1D0A4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17812 pso_num=27 pso_serial#=164 2025-06-28 22:05:58.921819 :AAF1D0A5:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17812 (legacy spawn) 2025-06-28 22:05:58.928305 :AAF1D0A6: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-28 22:05:58.928487 :AAF1D0AF: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-28 22:05:58.929277 :AAF1D0B0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:00.293996 :AAF1E464:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:00.292 2025-06-28 22:07:00.293998 :AAF1E465:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12488 pso_num=27 pso_serial#=165 2025-06-28 22:07:00.294133 :AAF1E466:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12488 (legacy spawn) 2025-06-28 22:07:00.300509 :AAF1E467: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-28 22:07:00.300683 :AAF1E46C: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-28 22:07:00.301491 :AAF1E471:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:00.401124 :AAF1E474:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:00.386 2025-06-28 22:07:00.401126 :AAF1E475:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18104 pso_num=27 pso_serial#=166 2025-06-28 22:07:00.401264 :AAF1E476:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18104 (legacy spawn) 2025-06-28 22:07:00.408142 :AAF1E477: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-28 22:07:00.408315 :AAF1E480: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-28 22:07:00.409231 :AAF1E481:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:00.426103 :AAF1E48F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:00.417 2025-06-28 22:07:00.426105 :AAF1E490:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5096 pso_num=27 pso_serial#=167 2025-06-28 22:07:00.426259 :AAF1E491:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5096 (legacy spawn) 2025-06-28 22:07:00.433443 :AAF1E492: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-28 22:07:00.433624 :AAF1E49A: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-28 22:07:00.434545 :AAF1E49C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:01.325979 :AAF1E514:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:01.318 2025-06-28 22:07:01.325981 :AAF1E515:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17088 pso_num=27 pso_serial#=168 2025-06-28 22:07:01.326115 :AAF1E516:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17088 (legacy spawn) 2025-06-28 22:07:01.332567 :AAF1E517: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-28 22:07:01.332738 :AAF1E520: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-28 22:07:01.333551 :AAF1E521:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:03.596504 :AAF1E5B7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:03.584 2025-06-28 22:07:03.596506 :AAF1E5B8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9576 pso_num=27 pso_serial#=169 2025-06-28 22:07:03.596643 :AAF1E5B9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9576 (legacy spawn) 2025-06-28 22:07:03.603309 :AAF1E5BA: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-28 22:07:03.603484 :AAF1E5BF: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-28 22:07:03.604273 :AAF1E5C4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:03.612946 :AAF1E5C7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:03.599 2025-06-28 22:07:03.612948 :AAF1E5C8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9160 pso_num=27 pso_serial#=170 2025-06-28 22:07:03.613079 :AAF1E5C9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9160 (legacy spawn) 2025-06-28 22:07:03.619522 :AAF1E5CA: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-28 22:07:03.619684 :AAF1E5D3: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-28 22:07:03.620468 :AAF1E5D4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:05.414545 :AAF1E641:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:05.403 2025-06-28 22:07:05.414548 :AAF1E642:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5952 pso_num=27 pso_serial#=171 2025-06-28 22:07:05.414696 :AAF1E643:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5952 (legacy spawn) 2025-06-28 22:07:05.421511 :AAF1E644: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-28 22:07:05.421695 :AAF1E649: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-28 22:07:05.422520 :AAF1E64E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:07:05.431512 :AAF1E651:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:07:05.419 2025-06-28 22:07:05.431514 :AAF1E652:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10676 pso_num=27 pso_serial#=172 2025-06-28 22:07:05.431657 :AAF1E653:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10676 (legacy spawn) 2025-06-28 22:07:05.438721 :AAF1E654: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-28 22:07:05.438914 :AAF1E65D: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-28 22:07:05.439819 :AAF1E65E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:08:03.496328 :AAF1F95D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:08:03.488 2025-06-28 22:08:03.496330 :AAF1F95E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13624 pso_num=27 pso_serial#=173 2025-06-28 22:08:03.496467 :AAF1F95F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13624 (legacy spawn) 2025-06-28 22:08:03.502971 :AAF1F960: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-28 22:08:03.503144 :AAF1F965: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-28 22:08:03.503970 :AAF1F96A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:08:05.722626 :AAF1FA35:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:08:05.717 2025-06-28 22:08:05.722628 :AAF1FA36:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18124 pso_num=27 pso_serial#=174 2025-06-28 22:08:05.722770 :AAF1FA37:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18124 osp_idx=25 osp_ver=111872 osp_pg=0 (spawn #332527) 2025-06-28 22:08:05.722771 :AAF1FA38: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-28 22:08:05.722772 :AAF1FA39:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=213 time=4124228907 2025-06-28 22:08:05.722772 :AAF1FA3A:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 22:08:05.722773 :AAF1FA3B:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 22:08:05.722773 :AAF1FA3C:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 22:08:05.722773 :AAF1FA3D:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 22:08:05.722774 :AAF1FA3E:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 22:08:05.723272 :AAF1FA3F: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-28 22:08:05.723319 :AAF1FA45: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-28 22:08:05.727419 :AAF1FA5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 22:08:05.727464 :AAF1FA5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 22:08:05.727724 :AAF1FA5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 22:08:05.727741 :AAF1FA5F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 22:08:05.727970 :AAF1FA60:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 22:08:05.727984 :AAF1FA61:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 22:08:05.728186 :AAF1FA62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 22:08:05.728199 :AAF1FA63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 22:08:05.728408 :AAF1FA64:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 22:08:05.728420 :AAF1FA65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 22:08:05.728620 :AAF1FA66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-28 22:08:05.728633 :AAF1FA67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-28 22:08:05.731172 :AAF1FA9A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-28 22:08:05.925655 :AAF1FEB3:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-28 22:08:05.920 2025-06-28 22:08:05.925657 :AAF1FEB4:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9288 pso_num=27 pso_serial#=175 2025-06-28 22:08:05.925793 :AAF1FEB5:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=9288 osp_idx=25 osp_ver=111873 osp_pg=0 (spawn #332529) 2025-06-28 22:08:05.925794 :AAF1FEB6: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-28 22:08:05.925795 :AAF1FEB7:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=214 time=4124229110 2025-06-28 22:08:05.925795 :AAF1FEB8:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-28 22:08:05.925796 :AAF1FEB9:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-28 22:08:05.925796 :AAF1FEBA:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-28 22:08:05.925796 :AAF1FEBB:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-28 22:08:05.925796 :AAF1FEBC:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-28 22:08:05.926307 :AAF1FEC6: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: 9288, J002)