Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250718081701\orcl_w000_4224_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:36227M/63366M, Ph+PgF:42142M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 4224, image: ORACLE.EXE (W000) *** 2025-07-18 08:17:02.009 *** SESSION ID:(201.52189) 2025-07-18 08:17:02.009 *** 2025-07-18 08:17:02.009 Process diagnostic dump for ORACLE.EXE (W000), OS id=4224, pid: 25, proc_ser: 102, sid: 201, sess_ser: 52189 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=3.760368 sec, exc=3.760368 sec, total=3.760368 sec wait times: max=5.000000 sec, heur=23.784936 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.000018 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=5.001388 sec, exc=5.001388 sec, total=5.001388 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.015180 sec, exc=5.015180 sec, total=5.015180 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=5.005643 sec, exc=5.005643 sec, total=5.005643 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000042 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.002241 sec, exc=5.002241 sec, total=5.002241 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.001876 sec of elapsed time 4: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x1, filetype=0x2 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.000154 sec, exc=0.000154 sec, total=0.000154 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000167 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.375149 sec, exc=0.375149 sec, total=0.375149 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000050 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.004656 sec, exc=5.004656 sec, total=5.004656 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.014724 sec, exc=5.014724 sec, total=5.014724 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.001720 sec, exc=5.001720 sec, total=5.001720 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.001123 sec, exc=5.001123 sec, total=5.001123 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 201 serial 52189 --------------------------------------------------- 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 --------------------------------------------------- [70 samples, 08:15:52 - 08:17:02] idle wait at each sample [session created at: 08:15:52] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-18 08:17:02.009 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 4224, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-18 08:00:51.078484 :B390C119:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:00:51.078484 :B390C11A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=1506426970 2025-07-18 08:00:51.078485 :B390C11B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:00:51.078485 :B390C11C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:00:51.078485 :B390C11D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:00:51.078486 :B390C11E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:00:51.078486 :B390C11F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:00:51.078972 :B390C120: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-07-18 08:01:16.109692 :B390CA2E: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-07-18 08:01:46.206018 :B390D474:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:01:51.170594 :B390D63C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:01:51.158 2025-07-18 08:01:51.170596 :B390D63D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16728 pso_num=25 pso_serial#=88 2025-07-18 08:01:51.170774 :B390D63E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16728 osp_idx=23 osp_ver=201395 osp_pg=0 (spawn #397222) 2025-07-18 08:01:51.170775 :B390D63F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:01:51.170775 :B390D640:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=1506487064 2025-07-18 08:01:51.170776 :B390D641:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:01:51.170776 :B390D642:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:01:51.170777 :B390D643:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:01:51.170777 :B390D644:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:01:51.170777 :B390D645:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:01:51.171305 :B390D646: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-07-18 08:02:21.255262 :B390E0F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:02:51.268203 :B390EB21:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:02:51.256 2025-07-18 08:02:51.268205 :B390EB22:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19056 pso_num=25 pso_serial#=89 2025-07-18 08:02:51.268340 :B390EB23:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19056 osp_idx=23 osp_ver=201396 osp_pg=0 (spawn #397224) 2025-07-18 08:02:51.268340 :B390EB24:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:02:51.268341 :B390EB25:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=1506547158 2025-07-18 08:02:51.268341 :B390EB26:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:02:51.268342 :B390EB27:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:02:51.268342 :B390EB28:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:02:51.268342 :B390EB29:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:02:51.268343 :B390EB2A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:02:51.268817 :B390EB2B: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-07-18 08:03:21.356021 :B390F52B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:03:51.361059 :B390FEF1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:03:51.349 2025-07-18 08:03:51.361061 :B390FEF2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13376 pso_num=25 pso_serial#=90 2025-07-18 08:03:51.361185 :B390FEF3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13376 osp_idx=23 osp_ver=201397 osp_pg=0 (spawn #397226) 2025-07-18 08:03:51.361186 :B390FEF4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:03:51.361186 :B390FEF5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=17 time=1506607251 2025-07-18 08:03:51.361186 :B390FEF6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:03:51.361186 :B390FEF7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:03:51.361186 :B390FEF8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:03:51.361187 :B390FEF9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:03:51.361187 :B390FEFA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:03:51.361666 :B390FEFB: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-07-18 08:04:21.471300 :B39109B4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:04:51.473840 :B39113B3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:04:51.463 2025-07-18 08:04:51.473842 :B39113B4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11784 pso_num=25 pso_serial#=91 2025-07-18 08:04:51.473963 :B39113B5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11784 osp_idx=23 osp_ver=201398 osp_pg=0 (spawn #397229) 2025-07-18 08:04:51.473964 :B39113B6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:04:51.473964 :B39113B7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=18 time=1506667361 2025-07-18 08:04:51.473965 :B39113B8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:04:51.473965 :B39113B9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:04:51.473965 :B39113BA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:04:51.473965 :B39113BB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:04:51.473966 :B39113BC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:04:51.474466 :B39113BD: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-07-18 08:05:21.565191 :B3912328:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:05:51.591791 :B3912D4E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:05:51.580 2025-07-18 08:05:51.591793 :B3912D4F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17888 pso_num=25 pso_serial#=92 2025-07-18 08:05:51.591914 :B3912D50:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17888 osp_idx=23 osp_ver=201399 osp_pg=0 (spawn #397232) 2025-07-18 08:05:51.591914 :B3912D51:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:05:51.591915 :B3912D52:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=19 time=1506727486 2025-07-18 08:05:51.591915 :B3912D53:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:05:51.591915 :B3912D54:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:05:51.591916 :B3912D55:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:05:51.591916 :B3912D56:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:05:51.591916 :B3912D57:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:05:51.592422 :B3912D58: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-07-18 08:06:21.657995 :B3913872:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:06:51.672846 :B391425C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:06:51.661 2025-07-18 08:06:51.672848 :B391425D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16108 pso_num=25 pso_serial#=93 2025-07-18 08:06:51.672986 :B391425E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16108 osp_idx=23 osp_ver=201400 osp_pg=0 (spawn #397234) 2025-07-18 08:06:51.672986 :B391425F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:06:51.672987 :B3914260:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=20 time=1506787564 2025-07-18 08:06:51.672987 :B3914261:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:06:51.672988 :B3914262:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:06:51.672988 :B3914263:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:06:51.672988 :B3914264:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:06:51.672989 :B3914265:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:06:51.673509 :B3914266: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-07-18 08:07:21.749231 :B3914C98:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:07:51.788192 :B39156BB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:07:51.776 2025-07-18 08:07:51.788194 :B39156BC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17620 pso_num=25 pso_serial#=94 2025-07-18 08:07:51.788335 :B39156BD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17620 osp_idx=23 osp_ver=201401 osp_pg=0 (spawn #397236) 2025-07-18 08:07:51.788336 :B39156BE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:07:51.788336 :B39156BF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=21 time=1506847673 2025-07-18 08:07:51.788337 :B39156C0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:07:51.788337 :B39156C1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:07:51.788337 :B39156C2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:07:51.788338 :B39156C3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:07:51.788338 :B39156C4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:07:51.788829 :B39156C5: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-07-18 08:08:21.878059 :B39160F3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:08:51.898664 :B3916B45:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:08:51.887 2025-07-18 08:08:51.898666 :B3916B46:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3592 pso_num=25 pso_serial#=95 2025-07-18 08:08:51.898804 :B3916B47:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3592 osp_idx=23 osp_ver=201402 osp_pg=0 (spawn #397239) 2025-07-18 08:08:51.898804 :B3916B48:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:08:51.898805 :B3916B49:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=22 time=1506907783 2025-07-18 08:08:51.898805 :B3916B4A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:08:51.898805 :B3916B4B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:08:51.898806 :B3916B4C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:08:51.898806 :B3916B4D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:08:51.898806 :B3916B4E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:08:51.899269 :B3916B4F: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-07-18 08:09:21.972499 :B3917638:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:09:51.962336 :B3918027:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:09:51.951 2025-07-18 08:09:51.962338 :B3918028:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15508 pso_num=25 pso_serial#=96 2025-07-18 08:09:51.962465 :B3918029:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15508 osp_idx=23 osp_ver=201403 osp_pg=0 (spawn #397241) 2025-07-18 08:09:51.962466 :B391802A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:09:51.962466 :B391802B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=23 time=1506967845 2025-07-18 08:09:51.962466 :B391802C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:09:51.962466 :B391802D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:09:51.962467 :B391802E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:09:51.962467 :B391802F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:09:51.962468 :B3918030:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:09:51.962952 :B3918031: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-07-18 08:10:22.034670 :B3918A3D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:10:52.056258 :B391946C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:10:52.044 2025-07-18 08:10:52.056261 :B391946D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19244 pso_num=25 pso_serial#=97 2025-07-18 08:10:52.056373 :B391946E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19244 osp_idx=23 osp_ver=201404 osp_pg=0 (spawn #397243) 2025-07-18 08:10:52.056374 :B391946F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:10:52.056374 :B3919470:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=24 time=1507027939 2025-07-18 08:10:52.056375 :B3919471:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:10:52.056375 :B3919472:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:10:52.056375 :B3919473:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:10:52.056376 :B3919474:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:10:52.056376 :B3919475:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:10:52.056875 :B3919476: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-07-18 08:11:17.075058 :B3919CCB: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-07-18 08:11:47.163345 :B391A700:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:11:52.119885 :B391A8E3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:11:52.109 2025-07-18 08:11:52.119887 :B391A8E4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19372 pso_num=25 pso_serial#=98 2025-07-18 08:11:52.120014 :B391A8E5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19372 osp_idx=23 osp_ver=201405 osp_pg=0 (spawn #397245) 2025-07-18 08:11:52.120014 :B391A8E6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:11:52.120015 :B391A8E7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=25 time=1507088001 2025-07-18 08:11:52.120015 :B391A8E8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:11:52.120016 :B391A8E9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:11:52.120016 :B391A8EA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:11:52.120016 :B391A8EB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:11:52.120017 :B391A8EC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:11:52.120513 :B391A8ED: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-07-18 08:12:22.208292 :B391B332:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:12:52.213771 :B391BD32:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:12:52.203 2025-07-18 08:12:52.213773 :B391BD33:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19056 pso_num=25 pso_serial#=99 2025-07-18 08:12:52.213900 :B391BD34:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19056 osp_idx=23 osp_ver=201406 osp_pg=0 (spawn #397247) 2025-07-18 08:12:52.213901 :B391BD35:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:12:52.213901 :B391BD36:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=26 time=1507148095 2025-07-18 08:12:52.213901 :B391BD37:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:12:52.213902 :B391BD38:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:12:52.213902 :B391BD39:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:12:52.213902 :B391BD3A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:12:52.213903 :B391BD3B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:12:52.214394 :B391BD3C: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-07-18 08:13:22.280086 :B391C74D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:13:52.287499 :B391D176:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:13:52.276 2025-07-18 08:13:52.287501 :B391D177:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16632 pso_num=25 pso_serial#=100 2025-07-18 08:13:52.287634 :B391D178:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16632 osp_idx=23 osp_ver=201407 osp_pg=0 (spawn #397250) 2025-07-18 08:13:52.287635 :B391D179:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:13:52.287635 :B391D17A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=27 time=1507208173 2025-07-18 08:13:52.287635 :B391D17B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:13:52.287636 :B391D17C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:13:52.287636 :B391D17D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:13:52.287636 :B391D17E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:13:52.287637 :B391D17F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:13:52.288111 :B391D180: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-07-18 08:14:22.362907 :B391DC15:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:14:52.382312 :B391E627:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:14:52.371 2025-07-18 08:14:52.382314 :B391E628:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18736 pso_num=25 pso_serial#=101 2025-07-18 08:14:52.382454 :B391E629:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18736 osp_idx=23 osp_ver=201408 osp_pg=0 (spawn #397252) 2025-07-18 08:14:52.382455 :B391E62A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:14:52.382456 :B391E62B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=28 time=1507268267 2025-07-18 08:14:52.382456 :B391E62C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:14:52.382457 :B391E62D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:14:52.382458 :B391E62E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:14:52.382459 :B391E62F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:14:52.382459 :B391E630:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:14:52.382976 :B391E631: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-07-18 08:15:22.469390 :B391F023:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-18 08:15:52.790552 :B391FA61:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-18 08:15:52.785 2025-07-18 08:15:52.790554 :B391FA62:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4224 pso_num=25 pso_serial#=102 2025-07-18 08:15:52.790691 :B391FA63:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4224 osp_idx=23 osp_ver=201409 osp_pg=0 (spawn #397255) 2025-07-18 08:15:52.790691 :B391FA64:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-18 08:15:52.790692 :B391FA65:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=29 time=1507328689 2025-07-18 08:15:52.790692 :B391FA66:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-18 08:15:52.790693 :B391FA67:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-18 08:15:52.790693 :B391FA68:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-18 08:15:52.790693 :B391FA69:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-18 08:15:52.790693 :B391FA6A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-18 08:15:52.791188 :B391FA6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-18 08:15:52.791234 :B391FA74:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-18 08:16:38.230330 :B3920968:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 4224, W000)