Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705180841\orcl_j002_18104_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:37395M/63366M, Ph+PgF:43382M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 18104, image: ORACLE.EXE (J002) *** 2025-07-05 18:08:41.874 *** SESSION ID:(219.2139) 2025-07-05 18:08:41.874 *** 2025-07-05 18:08:41.874 Process diagnostic dump for ORACLE.EXE (J002), OS id=18104, pid: 27, proc_ser: 207, sid: 219, sess_ser: 2139 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18104 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.139829 sec, exc=0.139829 sec, total=0.139829 sec wait times: max=0.500000 sec, heur=8.115544 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.514073 sec, exc=0.514073 sec, total=0.514073 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499968 sec, exc=0.499968 sec, total=0.499968 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500031 sec, exc=0.500031 sec, total=0.500031 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499010 sec, exc=0.499010 sec, total=0.499010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.454109 sec, exc=0.454109 sec, total=0.454109 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510120 sec, exc=0.510120 sec, total=0.510120 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 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=7 seq_num=8 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000010 sec of elapsed time Sampled Session History of session 219 serial 2139 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 18:08:33 - 18:08:41] idle wait at each sample [session created at: 18:08:33] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-05 18:08:41.874 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 18104, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-05 18:03:43.721230 :ADF43B98: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-07-05 18:03:43.722099 :ADF43B99:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:03:49.185366 :ADF43D8F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:03:49.177 2025-07-05 18:03:49.185368 :ADF43D90:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17544 pso_num=27 pso_serial#=181 2025-07-05 18:03:49.185497 :ADF43D91:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17544 (legacy spawn) 2025-07-05 18:03:49.192010 :ADF43D92: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-07-05 18:03:49.192174 :ADF43D97: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-07-05 18:03:49.192973 :ADF43D9C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:03:50.974721 :ADF43E0E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:03:50.969 2025-07-05 18:03:50.974724 :ADF43E0F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4624 pso_num=27 pso_serial#=182 2025-07-05 18:03:50.974861 :ADF43E10:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=4624 osp_idx=25 osp_ver=132025 osp_pg=0 (spawn #355283) 2025-07-05 18:03:50.974861 :ADF43E11:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:03:50.974862 :ADF43E12:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=254 time=419406861 2025-07-05 18:03:50.974862 :ADF43E13:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:03:50.974862 :ADF43E14:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:03:50.974863 :ADF43E15:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:03:50.974863 :ADF43E16:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:03:50.974863 :ADF43E17:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:03:50.975396 :ADF43E18: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-07-05 18:03:50.975442 :ADF43E1E: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-07-05 18:03:50.983597 :ADF43E2D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:07.412507 :ADF44397:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:07.402 2025-07-05 18:04:07.412509 :ADF44398:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5952 pso_num=27 pso_serial#=183 2025-07-05 18:04:07.412654 :ADF44399:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5952 (legacy spawn) 2025-07-05 18:04:07.419255 :ADF4439A: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-07-05 18:04:07.419428 :ADF443A3: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-07-05 18:04:07.420262 :ADF443A4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:41.811771 :ADF44F22:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:41.798 2025-07-05 18:04:41.811772 :ADF44F23:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5196 pso_num=27 pso_serial#=184 2025-07-05 18:04:41.811913 :ADF44F24:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5196 (legacy spawn) 2025-07-05 18:04:41.818823 :ADF44F25: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-07-05 18:04:41.819003 :ADF44F2A: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-07-05 18:04:41.819848 :ADF44F2F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:51.408102 :ADF45255:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:51.400 2025-07-05 18:04:51.408105 :ADF45256:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18240 pso_num=27 pso_serial#=185 2025-07-05 18:04:51.408248 :ADF45257:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18240 (legacy spawn) 2025-07-05 18:04:51.414859 :ADF45258: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-07-05 18:04:51.415041 :ADF45261: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-07-05 18:04:51.415876 :ADF45262:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:53.570361 :ADF45302:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:53.556 2025-07-05 18:04:53.570364 :ADF45303:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8632 pso_num=27 pso_serial#=186 2025-07-05 18:04:53.570512 :ADF45304:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8632 (legacy spawn) 2025-07-05 18:04:53.577117 :ADF45305: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-07-05 18:04:53.577293 :ADF4530A: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-07-05 18:04:53.578089 :ADF4530F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:53.596064 :ADF45312:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:53.587 2025-07-05 18:04:53.596066 :ADF45313:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16648 pso_num=27 pso_serial#=187 2025-07-05 18:04:53.596208 :ADF45314:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16648 (legacy spawn) 2025-07-05 18:04:53.602803 :ADF45320: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-07-05 18:04:53.602962 :ADF45329: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-07-05 18:04:53.603792 :ADF4532A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:56.790353 :ADF45425:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:56.776 2025-07-05 18:04:56.790355 :ADF45426:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11420 pso_num=27 pso_serial#=188 2025-07-05 18:04:56.790489 :ADF45427:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11420 (legacy spawn) 2025-07-05 18:04:56.797075 :ADF45428: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-07-05 18:04:56.797243 :ADF4542D: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-07-05 18:04:56.798053 :ADF45432:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:04:56.806841 :ADF45435:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:04:56.792 2025-07-05 18:04:56.806843 :ADF45436:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18364 pso_num=27 pso_serial#=189 2025-07-05 18:04:56.806982 :ADF45437:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18364 (legacy spawn) 2025-07-05 18:04:56.813563 :ADF45438: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-07-05 18:04:56.813745 :ADF45441: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-07-05 18:04:56.814588 :ADF45442:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:05:49.738067 :ADF46CFC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:05:49.728 2025-07-05 18:05:49.738069 :ADF46CFD:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8712 pso_num=27 pso_serial#=190 2025-07-05 18:05:49.738215 :ADF46CFE:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8712 (legacy spawn) 2025-07-05 18:05:49.745123 :ADF46CFF: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-07-05 18:05:49.745292 :ADF46D04: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-07-05 18:05:49.760420 :ADF46D09:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:05:49.768340 :ADF46D0C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:05:49.759 2025-07-05 18:05:49.768342 :ADF46D0D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9020 pso_num=27 pso_serial#=191 2025-07-05 18:05:49.768492 :ADF46D0E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9020 (legacy spawn) 2025-07-05 18:05:49.775179 :ADF46D0F: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-07-05 18:05:49.775342 :ADF46D14: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-07-05 18:05:49.776162 :ADF46D19:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:05:53.591011 :ADF46E2D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:05:53.582 2025-07-05 18:05:53.591013 :ADF46E2E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18360 pso_num=27 pso_serial#=192 2025-07-05 18:05:53.591148 :ADF46E2F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18360 (legacy spawn) 2025-07-05 18:05:53.597708 :ADF46E30: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-07-05 18:05:53.597895 :ADF46E35: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-07-05 18:05:53.598782 :ADF46E3A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:06.311391 :ADF47286:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:06.297 2025-07-05 18:06:06.311393 :ADF47287:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15152 pso_num=27 pso_serial#=193 2025-07-05 18:06:06.311531 :ADF47288:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15152 (legacy spawn) 2025-07-05 18:06:06.318192 :ADF47289: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-07-05 18:06:06.318360 :ADF4728E: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-07-05 18:06:06.319161 :ADF47293:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:06.328164 :ADF47296:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:06.328 2025-07-05 18:06:06.328166 :ADF47297:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17804 pso_num=27 pso_serial#=194 2025-07-05 18:06:06.328311 :ADF47298:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17804 (legacy spawn) 2025-07-05 18:06:06.335082 :ADF47299: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-07-05 18:06:06.335255 :ADF472A2: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-07-05 18:06:06.336108 :ADF472A3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:42.592854 :ADF47EC9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:42.589 2025-07-05 18:06:42.592856 :ADF47ECA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11340 pso_num=27 pso_serial#=195 2025-07-05 18:06:42.593007 :ADF47ECB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11340 (legacy spawn) 2025-07-05 18:06:42.599714 :ADF47ECC: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-07-05 18:06:42.599884 :ADF47ED1: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-07-05 18:06:42.600674 :ADF47ED6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:42.610267 :ADF47ED9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:42.605 2025-07-05 18:06:42.610269 :ADF47EDA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13808 pso_num=27 pso_serial#=196 2025-07-05 18:06:42.610430 :ADF47EDB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13808 (legacy spawn) 2025-07-05 18:06:42.617161 :ADF47EDC: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-07-05 18:06:42.617315 :ADF47EE5: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-07-05 18:06:42.618105 :ADF47EF4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:45.727815 :ADF47FEF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:45.719 2025-07-05 18:06:45.727817 :ADF47FF0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4432 pso_num=27 pso_serial#=197 2025-07-05 18:06:45.727972 :ADF47FF1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4432 (legacy spawn) 2025-07-05 18:06:45.735064 :ADF47FF2: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-07-05 18:06:45.735230 :ADF47FFB: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-07-05 18:06:45.736177 :ADF47FFC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:45.745746 :ADF47FFF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:45.734 2025-07-05 18:06:45.745748 :ADF48000:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=876 pso_num=27 pso_serial#=198 2025-07-05 18:06:45.745899 :ADF48001:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=876 (legacy spawn) 2025-07-05 18:06:45.753049 :ADF48002: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-07-05 18:06:45.753210 :ADF4800B: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-07-05 18:06:45.754123 :ADF4800C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:55.748924 :ADF4836C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:55.740 2025-07-05 18:06:55.748926 :ADF4836D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15376 pso_num=27 pso_serial#=199 2025-07-05 18:06:55.749062 :ADF4836E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15376 (legacy spawn) 2025-07-05 18:06:55.756087 :ADF4836F: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-07-05 18:06:55.756256 :ADF48374: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-07-05 18:06:55.757203 :ADF48379:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:06:57.954813 :ADF48413:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:06:57.943 2025-07-05 18:06:57.954815 :ADF48414:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14996 pso_num=27 pso_serial#=200 2025-07-05 18:06:57.954954 :ADF48415:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14996 (legacy spawn) 2025-07-05 18:06:57.961696 :ADF48416: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-07-05 18:06:57.961860 :ADF4841F: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-07-05 18:06:57.962664 :ADF48420:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:07:55.076989 :ADF49739:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:07:55.063 2025-07-05 18:07:55.076991 :ADF4973A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16604 pso_num=27 pso_serial#=201 2025-07-05 18:07:55.077132 :ADF4973B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16604 (legacy spawn) 2025-07-05 18:07:55.083753 :ADF49764: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-07-05 18:07:55.083786 :ADF49767: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-07-05 18:07:55.083939 :ADF4976E: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-07-05 18:07:55.084736 :ADF4977B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:07:55.093709 :ADF49789:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:07:55.094 2025-07-05 18:07:55.093710 :ADF4978A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16700 pso_num=27 pso_serial#=202 2025-07-05 18:07:55.093845 :ADF4978B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16700 (legacy spawn) 2025-07-05 18:07:55.112506 :ADF4978C: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-07-05 18:07:55.112688 :ADF49795: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-07-05 18:07:55.113513 :ADF49796:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:07:57.935882 :ADF49854:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:07:57.927 2025-07-05 18:07:57.935884 :ADF49855:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16976 pso_num=27 pso_serial#=203 2025-07-05 18:07:57.936040 :ADF49856:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16976 (legacy spawn) 2025-07-05 18:07:57.943058 :ADF49857: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-07-05 18:07:57.943243 :ADF49860: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-07-05 18:07:57.944151 :ADF49861:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:07:58.190269 :ADF49897:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:07:58.177 2025-07-05 18:07:58.190272 :ADF49898:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18268 pso_num=27 pso_serial#=204 2025-07-05 18:07:58.190412 :ADF49899:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18268 (legacy spawn) 2025-07-05 18:07:58.196935 :ADF4989A: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-07-05 18:07:58.197101 :ADF498A0: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-07-05 18:07:58.197901 :ADF498A4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:07:58.206775 :ADF498A7:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:07:58.192 2025-07-05 18:07:58.206778 :ADF498A8:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17472 pso_num=27 pso_serial#=205 2025-07-05 18:07:58.206917 :ADF498A9:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17472 (legacy spawn) 2025-07-05 18:07:58.213589 :ADF498AA: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-07-05 18:07:58.213749 :ADF498B3: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-07-05 18:07:58.214563 :ADF498B4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:08:06.251243 :ADF49B29:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:08:06.241 2025-07-05 18:08:06.251245 :ADF49B2A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5276 pso_num=27 pso_serial#=206 2025-07-05 18:08:06.251389 :ADF49B2B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5276 (legacy spawn) 2025-07-05 18:08:06.258083 :ADF49B2C: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-07-05 18:08:06.258260 :ADF49B31: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-07-05 18:08:06.259066 :ADF49B36:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-05 18:08:33.772321 :ADF4A8EB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 18:08:33.767 2025-07-05 18:08:33.772322 :ADF4A8EC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18104 pso_num=27 pso_serial#=207 2025-07-05 18:08:33.772527 :ADF4A8ED:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=18104 osp_idx=25 osp_ver=132050 osp_pg=0 (spawn #355297) 2025-07-05 18:08:33.772528 :ADF4A8EE:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:08:33.772529 :ADF4A8EF:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=255 time=419689673 2025-07-05 18:08:33.772529 :ADF4A8F0:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:08:33.772530 :ADF4A8F1:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:08:33.772530 :ADF4A8F2:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:08:33.772531 :ADF4A8F3:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:08:33.772531 :ADF4A8F4:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:08:33.773149 :ADF4A8FE: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 2025-07-05 18:08:39.234941 :ADF4ACFC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 18104, J002)