Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250623080020\orcl_j001_4200_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:36340M/63366M, Ph+PgF:42568M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 4200, image: ORACLE.EXE (J001) *** 2025-06-23 08:00:20.732 *** SESSION ID:(201.41851) 2025-06-23 08:00:20.732 *** 2025-06-23 08:00:20.732 Process diagnostic dump for ORACLE.EXE (J001), OS id=4200, pid: 25, proc_ser: 239, sid: 201, sess_ser: 41851 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4200 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.148849 sec, exc=0.148849 sec, total=0.148849 sec wait times: max=0.500000 sec, heur=23.704286 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.510136 sec, exc=0.510136 sec, total=0.510136 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=44 seq_num=45 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.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=41 seq_num=42 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.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=40 seq_num=41 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.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.510116 sec, exc=0.510116 sec, total=0.510116 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=38 seq_num=39 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.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=37 seq_num=38 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 Sampled Session History of session 201 serial 41851 --------------------------------------------------- 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 --------------------------------------------------- [24 samples, 07:59:57 - 08:00:20] idle wait at each sample [session created at: 07:59:57] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-23 08:00:20.732 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 4200, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-23 07:44:55.652418 :A8751F2F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:44:55.641 2025-06-23 07:44:55.652420 :A8751F30:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17568 pso_num=25 pso_serial#=224 2025-06-23 07:44:55.652556 :A8751F31:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17568 osp_idx=23 osp_ver=166341 osp_pg=0 (spawn #313863) 2025-06-23 07:44:55.652557 :A8751F32:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:44:55.652557 :A8751F33:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=3640438844 2025-06-23 07:44:55.652557 :A8751F34:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:44:55.652557 :A8751F35:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:44:55.652558 :A8751F36:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:44:55.652558 :A8751F37:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:44:55.652558 :A8751F38:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:44:55.653022 :A8751F39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:45:25.759150 :A8752922:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:45:55.770751 :A875331F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:45:55.760 2025-06-23 07:45:55.770753 :A8753320:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18024 pso_num=25 pso_serial#=225 2025-06-23 07:45:55.770897 :A8753321:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18024 osp_idx=23 osp_ver=166342 osp_pg=0 (spawn #313865) 2025-06-23 07:45:55.770897 :A8753322:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:45:55.770898 :A8753323:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=3640498954 2025-06-23 07:45:55.770899 :A8753324:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:45:55.770899 :A8753325:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:45:55.770900 :A8753326:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:45:55.770900 :A8753327:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:45:55.770900 :A8753328:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:45:55.771389 :A8753329:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:46:20.799934 :A8753B1B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-23 07:46:50.940642 :A875452C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:46:55.883087 :A875471E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:46:55.871 2025-06-23 07:46:55.883090 :A875471F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18376 pso_num=25 pso_serial#=226 2025-06-23 07:46:55.883228 :A8754720:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18376 osp_idx=23 osp_ver=166343 osp_pg=0 (spawn #313867) 2025-06-23 07:46:55.883229 :A8754721:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:46:55.883229 :A8754722:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=3640559063 2025-06-23 07:46:55.883229 :A8754723:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:46:55.883230 :A8754724:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:46:55.883230 :A8754725:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:46:55.883231 :A8754726:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:46:55.883231 :A8754727:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:46:55.883701 :A8754728:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:47:25.959287 :A8755137:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:47:55.959143 :A8755B5E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:47:55.948 2025-06-23 07:47:55.959145 :A8755B5F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15508 pso_num=25 pso_serial#=227 2025-06-23 07:47:55.959286 :A8755B60:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15508 osp_idx=23 osp_ver=166344 osp_pg=0 (spawn #313869) 2025-06-23 07:47:55.959286 :A8755B61:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:47:55.959287 :A8755B62:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=3640619141 2025-06-23 07:47:55.959287 :A8755B63:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:47:55.959288 :A8755B64:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:47:55.959288 :A8755B65:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:47:55.959288 :A8755B66:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:47:55.959289 :A8755B67:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:47:55.959775 :A8755B68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:48:26.012806 :A8756561:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:48:56.026633 :A8756F73:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:48:56.015 2025-06-23 07:48:56.026635 :A8756F74:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12128 pso_num=25 pso_serial#=228 2025-06-23 07:48:56.026775 :A8756F75:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12128 osp_idx=23 osp_ver=166345 osp_pg=0 (spawn #313872) 2025-06-23 07:48:56.026775 :A8756F76:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:48:56.026776 :A8756F77:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=3640679204 2025-06-23 07:48:56.026776 :A8756F78:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:48:56.026777 :A8756F79:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:48:56.026777 :A8756F7A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:48:56.026777 :A8756F7B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:48:56.026778 :A8756F7C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:48:56.027372 :A8756F7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:49:26.091529 :A8757A67:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:49:56.105230 :A8758475:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:49:56.094 2025-06-23 07:49:56.105232 :A8758476:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16724 pso_num=25 pso_serial#=229 2025-06-23 07:49:56.105368 :A8758477:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16724 osp_idx=23 osp_ver=166346 osp_pg=0 (spawn #313874) 2025-06-23 07:49:56.105369 :A8758478:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:49:56.105369 :A8758479:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=3640739282 2025-06-23 07:49:56.105369 :A875847A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:49:56.105369 :A875847B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:49:56.105370 :A875847C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:49:56.105370 :A875847D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:49:56.105370 :A875847E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:49:56.105843 :A875847F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:50:26.220889 :A8758E74:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:50:56.207587 :A875987F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:50:56.197 2025-06-23 07:50:56.207588 :A8759880:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15892 pso_num=25 pso_serial#=230 2025-06-23 07:50:56.207723 :A8759881:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15892 osp_idx=23 osp_ver=166347 osp_pg=0 (spawn #313876) 2025-06-23 07:50:56.207723 :A8759882:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:50:56.207723 :A8759883:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=3640799391 2025-06-23 07:50:56.207724 :A8759884:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:50:56.207724 :A8759885:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:50:56.207725 :A8759886:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:50:56.207725 :A8759887:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:50:56.207725 :A8759888:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:50:56.208194 :A8759889:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:51:26.280799 :A875A2BE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:51:56.311552 :A875ACA6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:51:56.301 2025-06-23 07:51:56.311555 :A875ACA7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17380 pso_num=25 pso_serial#=231 2025-06-23 07:51:56.311683 :A875ACA8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17380 osp_idx=23 osp_ver=166348 osp_pg=0 (spawn #313879) 2025-06-23 07:51:56.311684 :A875ACA9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:51:56.311684 :A875ACAA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=3640859500 2025-06-23 07:51:56.311685 :A875ACAB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:51:56.311685 :A875ACAC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:51:56.311685 :A875ACAD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:51:56.311686 :A875ACAE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:51:56.311686 :A875ACAF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:51:56.312181 :A875ACB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:52:26.372890 :A875B6B1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:52:56.401473 :A875C0B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:52:56.390 2025-06-23 07:52:56.401475 :A875C0B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3236 pso_num=25 pso_serial#=232 2025-06-23 07:52:56.401605 :A875C0B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3236 osp_idx=23 osp_ver=166349 osp_pg=0 (spawn #313881) 2025-06-23 07:52:56.401606 :A875C0B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:52:56.401606 :A875C0BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=3640919579 2025-06-23 07:52:56.401606 :A875C0BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:52:56.401607 :A875C0BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:52:56.401607 :A875C0BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:52:56.401607 :A875C0BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:52:56.401608 :A875C0BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:52:56.402069 :A875C0C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:53:26.481729 :A875CAD3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:53:56.486081 :A875D4DA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:53:56.474 2025-06-23 07:53:56.486083 :A875D4DB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3836 pso_num=25 pso_serial#=233 2025-06-23 07:53:56.486215 :A875D4DC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3836 osp_idx=23 osp_ver=166350 osp_pg=0 (spawn #313883) 2025-06-23 07:53:56.486215 :A875D4DD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:53:56.486216 :A875D4DE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=3640979672 2025-06-23 07:53:56.486216 :A875D4DF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:53:56.486217 :A875D4E0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:53:56.486217 :A875D4E1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:53:56.486218 :A875D4E2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:53:56.486218 :A875D4E3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:53:56.486688 :A875D4E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:54:26.571038 :A875DF43:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:54:56.610594 :A875E93B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:54:56.600 2025-06-23 07:54:56.610596 :A875E93C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17820 pso_num=25 pso_serial#=234 2025-06-23 07:54:56.610770 :A875E93D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17820 osp_idx=23 osp_ver=166351 osp_pg=0 (spawn #313885) 2025-06-23 07:54:56.610772 :A875E93E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:54:56.610772 :A875E93F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=3641039797 2025-06-23 07:54:56.610773 :A875E940:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:54:56.610773 :A875E941:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:54:56.610774 :A875E942:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:54:56.610774 :A875E943:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:54:56.610775 :A875E944:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:54:56.611293 :A875E945:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:55:26.677568 :A875F317:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:55:56.721778 :A875FCE2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:55:56.711 2025-06-23 07:55:56.721780 :A875FCE3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15428 pso_num=25 pso_serial#=235 2025-06-23 07:55:56.721916 :A875FCE4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15428 osp_idx=23 osp_ver=166352 osp_pg=0 (spawn #313887) 2025-06-23 07:55:56.721917 :A875FCE5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:55:56.721918 :A875FCE6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=3641099907 2025-06-23 07:55:56.721918 :A875FCE7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:55:56.721919 :A875FCE8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:55:56.721919 :A875FCE9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:55:56.721920 :A875FCEA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:55:56.721920 :A875FCEB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:55:56.722415 :A875FCEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:56:21.738456 :A876056E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-23 07:56:51.834851 :A8760F3C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:56:56.772427 :A8761100:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:56:56.761 2025-06-23 07:56:56.772429 :A8761101:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16720 pso_num=25 pso_serial#=236 2025-06-23 07:56:56.772566 :A8761102:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16720 osp_idx=23 osp_ver=166353 osp_pg=0 (spawn #313890) 2025-06-23 07:56:56.772566 :A8761103:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:56:56.772566 :A8761104:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=3641159954 2025-06-23 07:56:56.772567 :A8761105:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:56:56.772567 :A8761106:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:56:56.772568 :A8761107:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:56:56.772568 :A8761108:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:56:56.772568 :A8761109:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:56:56.773046 :A876110A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:57:26.827128 :A8761B14:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:57:56.861334 :A87625B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:57:56.849 2025-06-23 07:57:56.861337 :A87625B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13888 pso_num=25 pso_serial#=237 2025-06-23 07:57:56.861455 :A87625B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13888 osp_idx=23 osp_ver=166354 osp_pg=0 (spawn #313892) 2025-06-23 07:57:56.861455 :A87625B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:57:56.861456 :A87625BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=3641220047 2025-06-23 07:57:56.861456 :A87625BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:57:56.861457 :A87625BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:57:56.861457 :A87625BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:57:56.861458 :A87625BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:57:56.861458 :A87625BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:57:56.861987 :A87625C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:58:26.927291 :A8762F71:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:58:56.987389 :A8763992:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:58:56.977 2025-06-23 07:58:56.987391 :A8763993:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14064 pso_num=25 pso_serial#=238 2025-06-23 07:58:56.987559 :A8763994:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14064 osp_idx=23 osp_ver=166355 osp_pg=0 (spawn #313895) 2025-06-23 07:58:56.987559 :A8763995:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:58:56.987560 :A8763996:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=3641280172 2025-06-23 07:58:56.987560 :A8763997:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:58:56.987560 :A8763998:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:58:56.987561 :A8763999:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:58:56.987561 :A876399A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:58:56.987561 :A876399B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:58:56.988073 :A876399C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-23 07:59:27.060060 :A87644E1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 07:59:57.035389 :A8764EE2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 07:59:57.024 2025-06-23 07:59:57.035391 :A8764EE3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4200 pso_num=25 pso_serial#=239 2025-06-23 07:59:57.035574 :A8764EE4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4200 osp_idx=23 osp_ver=166356 osp_pg=0 (spawn #313897) 2025-06-23 07:59:57.035575 :A8764EE5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 07:59:57.035576 :A8764EE6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=3641340219 2025-06-23 07:59:57.035577 :A8764EE7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 07:59:57.035577 :A8764EE8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 07:59:57.035578 :A8764EE9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 07:59:57.035578 :A8764EEA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 07:59:57.035578 :A8764EEB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 07:59:57.036117 :A8764EEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] 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 25 (osid: 4200, J001)