Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426180655\orcl_j002_3500_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:37584M/63366M, Ph+PgF:45267M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 3500, image: ORACLE.EXE (J002) *** 2025-04-26 18:06:55.950 *** SESSION ID:(219.51063) 2025-04-26 18:06:55.950 *** 2025-04-26 18:06:55.950 Process diagnostic dump for ORACLE.EXE (J002), OS id=3500, pid: 27, proc_ser: 6, sid: 219, sess_ser: 51063 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3500 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.029741 sec, exc=0.029741 sec, total=0.029741 sec wait times: max=0.500000 sec, heur=7.030836 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.000005 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.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500087 sec, exc=0.500087 sec, total=0.500087 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.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.000008 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.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 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.506902 sec, exc=0.506902 sec, total=0.506902 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.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.000013 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.500125 sec, exc=0.500125 sec, total=0.500125 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 51063 --------------------------------------------------- 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, 18:06:48 - 18:06:55] idle wait at each sample [session created at: 18:06:48] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-26 18:06:55.950 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 3500, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-26 18:00:59.484498 :8F1C0DD0: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-04-26 18:00:59.484667 :8F1C0DD5: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-04-26 18:00:59.485497 :8F1C0DDA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:01:35.212526 :8F1C19E4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:01:35.199 2025-04-26 18:01:35.212528 :8F1C19E5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9360 pso_num=27 pso_serial#=236 2025-04-26 18:01:35.212661 :8F1C19E6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9360 (legacy spawn) 2025-04-26 18:01:35.219163 :8F1C19E7: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-04-26 18:01:35.219335 :8F1C19F0: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-04-26 18:01:35.220161 :8F1C19F1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:01:35.228750 :8F1C19F4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:01:35.215 2025-04-26 18:01:35.228751 :8F1C19F5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10552 pso_num=27 pso_serial#=237 2025-04-26 18:01:35.228881 :8F1C19F6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10552 (legacy spawn) 2025-04-26 18:01:35.235328 :8F1C19F7: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-04-26 18:01:35.235500 :8F1C19FF: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-04-26 18:01:35.236266 :8F1C1A01:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:01:42.156416 :8F1C1C6C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:01:42.146 2025-04-26 18:01:42.156418 :8F1C1C6D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8828 pso_num=27 pso_serial#=238 2025-04-26 18:01:42.156552 :8F1C1C6E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8828 (legacy spawn) 2025-04-26 18:01:42.163071 :8F1C1C6F: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-04-26 18:01:42.163240 :8F1C1C78: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-04-26 18:01:42.164009 :8F1C1C79:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:01:48.030568 :8F1C1E63:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:01:48.025 2025-04-26 18:01:48.030570 :8F1C1E64:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10996 pso_num=27 pso_serial#=239 2025-04-26 18:01:48.030716 :8F1C1E65:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10996 osp_idx=25 osp_ver=25483 osp_pg=0 (spawn #121040) 2025-04-26 18:01:48.030717 :8F1C1E66:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 18:01:48.030718 :8F1C1E67:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=164 time=2961309828 2025-04-26 18:01:48.030718 :8F1C1E68:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 18:01:48.030719 :8F1C1E69:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 18:01:48.030719 :8F1C1E6A:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 18:01:48.030719 :8F1C1E6B:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 18:01:48.030720 :8F1C1E6C:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 18:01:48.031285 :8F1C1E6D: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-04-26 18:01:48.031331 :8F1C1E73: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-04-26 18:01:48.039321 :8F1C1E85:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:02:01.696535 :8F1C22A5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:02:01.688 2025-04-26 18:02:01.696537 :8F1C22A6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10628 pso_num=27 pso_serial#=240 2025-04-26 18:02:01.696686 :8F1C22A7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10628 (legacy spawn) 2025-04-26 18:02:01.703144 :8F1C22A8: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-04-26 18:02:01.703315 :8F1C22AD: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-04-26 18:02:01.704150 :8F1C22B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:02:44.706020 :8F1C30E8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:02:44.703 2025-04-26 18:02:44.706022 :8F1C30E9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9484 pso_num=27 pso_serial#=241 2025-04-26 18:02:44.706159 :8F1C30EA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9484 (legacy spawn) 2025-04-26 18:02:44.712561 :8F1C30EB: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-04-26 18:02:44.712742 :8F1C30F4: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-04-26 18:02:44.713524 :8F1C30F5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:02:44.722118 :8F1C30F8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:02:44.718 2025-04-26 18:02:44.722120 :8F1C30F9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9576 pso_num=27 pso_serial#=242 2025-04-26 18:02:44.722260 :8F1C30FA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9576 (legacy spawn) 2025-04-26 18:02:44.728694 :8F1C30FB: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-04-26 18:02:44.728724 :8F1C30FE: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-04-26 18:02:44.728858 :8F1C3105: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-04-26 18:02:44.729645 :8F1C3112:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:02:47.897832 :8F1C3204:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:02:47.885 2025-04-26 18:02:47.897834 :8F1C3205:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9652 pso_num=27 pso_serial#=243 2025-04-26 18:02:47.897965 :8F1C3206:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9652 (legacy spawn) 2025-04-26 18:02:47.904687 :8F1C3207: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-04-26 18:02:47.904873 :8F1C320C: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-04-26 18:02:47.905716 :8F1C3211:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:02:47.924890 :8F1C3214:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:02:47.916 2025-04-26 18:02:47.924892 :8F1C3215:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4660 pso_num=27 pso_serial#=244 2025-04-26 18:02:47.925028 :8F1C3216:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4660 (legacy spawn) 2025-04-26 18:02:47.931754 :8F1C3217: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-04-26 18:02:47.931922 :8F1C3220: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-04-26 18:02:47.932787 :8F1C3221:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:02:50.252165 :8F1C3311:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:02:50.243 2025-04-26 18:02:50.252168 :8F1C3312:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9324 pso_num=27 pso_serial#=245 2025-04-26 18:02:50.252303 :8F1C3313:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9324 (legacy spawn) 2025-04-26 18:02:50.258781 :8F1C3314: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-04-26 18:02:50.258938 :8F1C3319: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-04-26 18:02:50.259747 :8F1C331E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:03:03.896980 :8F1C377D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:03:03.888 2025-04-26 18:03:03.896982 :8F1C377E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11052 pso_num=27 pso_serial#=246 2025-04-26 18:03:03.897122 :8F1C377F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11052 (legacy spawn) 2025-04-26 18:03:03.903552 :8F1C3780: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-04-26 18:03:03.903727 :8F1C3785: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-04-26 18:03:03.904538 :8F1C378A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:03:34.994815 :8F1C41BF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:03:34.987 2025-04-26 18:03:34.994816 :8F1C41C0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10612 pso_num=27 pso_serial#=247 2025-04-26 18:03:34.994925 :8F1C41C1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10612 (legacy spawn) 2025-04-26 18:03:35.001363 :8F1C41D6: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-04-26 18:03:35.001521 :8F1C41DF: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-04-26 18:03:35.002325 :8F1C41E3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:03:57.180910 :8F1C48E1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:03:57.175 2025-04-26 18:03:57.180912 :8F1C48E2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10400 pso_num=27 pso_serial#=248 2025-04-26 18:03:57.181047 :8F1C48E3:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10400 (legacy spawn) 2025-04-26 18:03:57.187431 :8F1C48E4: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-04-26 18:03:57.187603 :8F1C48EA: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-04-26 18:03:57.188399 :8F1C48EE:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:03:57.197019 :8F1C4919:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:03:57.190 2025-04-26 18:03:57.197022 :8F1C491A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9628 pso_num=27 pso_serial#=249 2025-04-26 18:03:57.197211 :8F1C491B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9628 (legacy spawn) 2025-04-26 18:03:57.203575 :8F1C491C: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-04-26 18:03:57.203733 :8F1C4925: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-04-26 18:03:57.204571 :8F1C4926:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:03:58.568416 :8F1C4984:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:03:58.560 2025-04-26 18:03:58.568419 :8F1C4985:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8364 pso_num=27 pso_serial#=250 2025-04-26 18:03:58.568564 :8F1C4986:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8364 (legacy spawn) 2025-04-26 18:03:58.575006 :8F1C49AA: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-04-26 18:03:58.575186 :8F1C49AF: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-04-26 18:03:58.576061 :8F1C49B4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:04:00.487308 :8F1C4A5A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:04:00.474 2025-04-26 18:04:00.487310 :8F1C4A5B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9508 pso_num=27 pso_serial#=251 2025-04-26 18:04:00.487443 :8F1C4A5C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9508 (legacy spawn) 2025-04-26 18:04:00.493901 :8F1C4A5D: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-04-26 18:04:00.494067 :8F1C4A62: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-04-26 18:04:00.494905 :8F1C4A67:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:04:00.503495 :8F1C4A6A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:04:00.490 2025-04-26 18:04:00.503498 :8F1C4A6B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5220 pso_num=27 pso_serial#=252 2025-04-26 18:04:00.503627 :8F1C4A6C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5220 (legacy spawn) 2025-04-26 18:04:00.510083 :8F1C4A6D: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-04-26 18:04:00.510245 :8F1C4A76: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-04-26 18:04:00.511034 :8F1C4A77:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:05:39.431624 :8F1C6D66:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:05:39.423 2025-04-26 18:05:39.431626 :8F1C6D67:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11148 pso_num=27 pso_serial#=253 2025-04-26 18:05:39.431763 :8F1C6D68:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11148 (legacy spawn) 2025-04-26 18:05:39.438238 :8F1C6D69: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-04-26 18:05:39.438413 :8F1C6D6E: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-04-26 18:05:39.439243 :8F1C6D73:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:05:41.158585 :8F1C6E05:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:05:41.150 2025-04-26 18:05:41.158587 :8F1C6E06:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9756 pso_num=27 pso_serial#=254 2025-04-26 18:05:41.158719 :8F1C6E07:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9756 (legacy spawn) 2025-04-26 18:05:41.165260 :8F1C6E08: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-04-26 18:05:41.165429 :8F1C6E0D: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-04-26 18:05:41.166258 :8F1C6E12:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:05:46.390781 :8F1C6FBA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:05:46.376 2025-04-26 18:05:46.390783 :8F1C6FBB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11172 pso_num=27 pso_serial#=255 2025-04-26 18:05:46.390926 :8F1C6FBC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11172 (legacy spawn) 2025-04-26 18:05:46.397409 :8F1C6FBD: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-04-26 18:05:46.397576 :8F1C6FC2: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-04-26 18:05:46.398371 :8F1C6FC7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:05:46.416346 :8F1C6FCA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:05:46.407 2025-04-26 18:05:46.416348 :8F1C6FCB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9584 pso_num=27 pso_serial#=1 2025-04-26 18:05:46.416493 :8F1C6FCC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9584 (legacy spawn) 2025-04-26 18:05:46.423367 :8F1C6FCD: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-04-26 18:05:46.423524 :8F1C6FD6: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-04-26 18:05:46.424493 :8F1C6FD7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:05:49.483522 :8F1C70CF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:05:49.470 2025-04-26 18:05:49.483524 :8F1C70D0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9600 pso_num=27 pso_serial#=2 2025-04-26 18:05:49.483662 :8F1C70D1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9600 (legacy spawn) 2025-04-26 18:05:49.490221 :8F1C70D2: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-04-26 18:05:49.490406 :8F1C70D7: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-04-26 18:05:49.491186 :8F1C70DC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:05:49.499805 :8F1C70DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:05:49.486 2025-04-26 18:05:49.499807 :8F1C70E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10636 pso_num=27 pso_serial#=3 2025-04-26 18:05:49.499943 :8F1C70E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10636 (legacy spawn) 2025-04-26 18:05:49.506387 :8F1C70E2: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-04-26 18:05:49.506555 :8F1C70E9: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-04-26 18:05:49.507353 :8F1C70EC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:06:41.560665 :8F1C8700:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:06:41.553 2025-04-26 18:06:41.560667 :8F1C8701:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11060 pso_num=27 pso_serial#=4 2025-04-26 18:06:41.560800 :8F1C8702:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11060 (legacy spawn) 2025-04-26 18:06:41.567243 :8F1C8703: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-04-26 18:06:41.567425 :8F1C8709: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-04-26 18:06:41.568291 :8F1C870D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:06:48.729133 :8F1C8997:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:06:48.724 2025-04-26 18:06:48.729135 :8F1C8998:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8508 pso_num=27 pso_serial#=5 2025-04-26 18:06:48.729270 :8F1C8999:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=8508 osp_idx=25 osp_ver=25504 osp_pg=0 (spawn #121051) 2025-04-26 18:06:48.729271 :8F1C899A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 18:06:48.729271 :8F1C899B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=165 time=2961610531 2025-04-26 18:06:48.729271 :8F1C899C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 18:06:48.729272 :8F1C899D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 18:06:48.729272 :8F1C899E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 18:06:48.729273 :8F1C899F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 18:06:48.729273 :8F1C89A0:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 18:06:48.729766 :8F1C89A1: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-04-26 18:06:48.729812 :8F1C89A7: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-04-26 18:06:48.750676 :8F1C8A3C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-04-26 18:06:48.932720 :8F1C8E35:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 04-26 18:06:48.927 2025-04-26 18:06:48.932722 :8F1C8E36:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3500 pso_num=27 pso_serial#=6 2025-04-26 18:06:48.932854 :8F1C8E37:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=3500 osp_idx=25 osp_ver=25505 osp_pg=0 (spawn #121054) 2025-04-26 18:06:48.932854 :8F1C8E38:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 18:06:48.932855 :8F1C8E39:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=166 time=2961610734 2025-04-26 18:06:48.932855 :8F1C8E3A:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 18:06:48.932856 :8F1C8E3B:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 18:06:48.932856 :8F1C8E3C:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 18:06:48.932856 :8F1C8E3D:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 18:06:48.932857 :8F1C8E3E:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 18:06:48.933342 :8F1C8E3F: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: 3500, J002)