Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250428220007\orcl_j002_8028_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250428220007\orcl_j002_8028_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:37541M/63366M, Ph+PgF:45143M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 8028, image: ORACLE.EXE (J002) *** 2025-04-28 22:00:07.666 *** SESSION ID:(193.41803) 2025-04-28 22:00:07.666 *** 2025-04-28 22:00:07.666 Process diagnostic dump for ORACLE.EXE (J002), OS id=8028, pid: 24, proc_ser: 208, sid: 193, sess_ser: 41803 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8028 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.094358 sec, exc=0.094358 sec, total=0.094358 sec wait times: max=0.500000 sec, heur=6.186064 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510083 sec, exc=0.510083 sec, total=0.510083 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 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=9 seq_num=10 snap_id=1 wait times: snap=0.505098 sec, exc=0.505098 sec, total=0.505098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.502089 sec, exc=0.502089 sec, total=0.502089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 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 193 serial 41803 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:01 - 22:00:07] idle wait at each sample [session created at: 22:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-28 22:00:07.666 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 8028, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-28 21:52:15.720958 :90158F0B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:52:15.720958 :90158F0C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:52:15.720959 :90158F0D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:52:15.720959 :90158F0E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:52:15.721604 :90158F0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:52:45.780779 :901598C8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:53:15.829590 :9015A284:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:53:15.818 2025-04-28 21:53:15.829592 :9015A285:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6600 pso_num=24 pso_serial#=198 2025-04-28 21:53:15.829726 :9015A286:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6600 osp_idx=22 osp_ver=188387 osp_pg=0 (spawn #128368) 2025-04-28 21:53:15.829726 :9015A287:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:53:15.829726 :9015A288:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=182 time=3147985312 2025-04-28 21:53:15.829727 :9015A289:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:53:15.829728 :9015A28A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:53:15.829728 :9015A28B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:53:15.829729 :9015A28C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:53:15.829729 :9015A28D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:53:15.830277 :9015A28E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:53:45.920075 :9015AC7A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:54:10.921057 :9015B52C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:54:10.910 2025-04-28 21:54:10.921059 :9015B52D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4352 pso_num=24 pso_serial#=199 2025-04-28 21:54:10.921188 :9015B52E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4352 osp_idx=22 osp_ver=188388 osp_pg=0 (spawn #128371) 2025-04-28 21:54:10.921189 :9015B52F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:54:10.921190 :9015B530:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=183 time=3148040390 2025-04-28 21:54:10.921190 :9015B531:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:54:10.921191 :9015B532:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:54:10.921191 :9015B533:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:54:10.921191 :9015B534:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:54:10.921192 :9015B535:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:54:10.921729 :9015B536:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:54:15.924828 :9015B690:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 21:54:46.053293 :9015C155:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:55:16.009146 :9015CB20:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:55:15.997 2025-04-28 21:55:16.009148 :9015CB21:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7276 pso_num=24 pso_serial#=200 2025-04-28 21:55:16.009301 :9015CB22:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7276 osp_idx=22 osp_ver=188389 osp_pg=0 (spawn #128373) 2025-04-28 21:55:16.009302 :9015CB23:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:55:16.009302 :9015CB24:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=184 time=3148105484 2025-04-28 21:55:16.009303 :9015CB25:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:55:16.009303 :9015CB26:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:55:16.009303 :9015CB27:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:55:16.009303 :9015CB28:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:55:16.009304 :9015CB29:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:55:16.009826 :9015CB2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:55:46.049573 :9015D4AD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:56:16.072269 :9015DE73:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:56:16.061 2025-04-28 21:56:16.072272 :9015DE74:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8548 pso_num=24 pso_serial#=201 2025-04-28 21:56:16.072414 :9015DE75:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8548 osp_idx=22 osp_ver=188390 osp_pg=0 (spawn #128375) 2025-04-28 21:56:16.072415 :9015DE76:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:56:16.072415 :9015DE77:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=185 time=3148165546 2025-04-28 21:56:16.072416 :9015DE78:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:56:16.072416 :9015DE79:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:56:16.072417 :9015DE7A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:56:16.072417 :9015DE7B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:56:16.072418 :9015DE7C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:56:16.072941 :9015DE7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:56:46.142432 :9015E84C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:57:16.181021 :9015F216:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:57:16.170 2025-04-28 21:57:16.181024 :9015F217:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10624 pso_num=24 pso_serial#=202 2025-04-28 21:57:16.181188 :9015F218:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10624 osp_idx=22 osp_ver=188391 osp_pg=0 (spawn #128377) 2025-04-28 21:57:16.181189 :9015F219:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:57:16.181189 :9015F21A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=186 time=3148225656 2025-04-28 21:57:16.181190 :9015F21B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:57:16.181190 :9015F21C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:57:16.181190 :9015F21D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:57:16.181191 :9015F21E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:57:16.181191 :9015F21F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:57:16.181680 :9015F220:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:57:46.251801 :9015FC47:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:58:16.299802 :9016061D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:58:16.288 2025-04-28 21:58:16.299804 :9016061E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9940 pso_num=24 pso_serial#=203 2025-04-28 21:58:16.299939 :9016061F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9940 osp_idx=22 osp_ver=188392 osp_pg=0 (spawn #128380) 2025-04-28 21:58:16.299940 :90160620:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:58:16.299940 :90160621:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=187 time=3148285781 2025-04-28 21:58:16.299941 :90160622:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:58:16.299941 :90160623:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:58:16.299942 :90160624:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:58:16.299942 :90160625:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:58:16.299942 :90160626:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:58:16.300427 :90160627:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:58:46.392639 :90160FD7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 21:59:16.365187 :901619E7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 21:59:16.354 2025-04-28 21:59:16.365189 :901619E8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8504 pso_num=24 pso_serial#=204 2025-04-28 21:59:16.365324 :901619E9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8504 osp_idx=22 osp_ver=188393 osp_pg=0 (spawn #128383) 2025-04-28 21:59:16.365324 :901619EA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 21:59:16.365325 :901619EB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=188 time=3148345843 2025-04-28 21:59:16.365325 :901619EC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 21:59:16.365326 :901619ED:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 21:59:16.365326 :901619EE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 21:59:16.365327 :901619EF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 21:59:16.365327 :901619F0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 21:59:16.365819 :901619F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 21:59:46.458779 :90162414:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 22:00:00.031874 :901628C5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 22:00:00.023 2025-04-28 22:00:00.031876 :901628C6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10200 pso_num=24 pso_serial#=205 2025-04-28 22:00:00.032013 :901628C7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10200 osp_idx=22 osp_ver=188394 osp_pg=0 (spawn #128385) 2025-04-28 22:00:00.032014 :901628C8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 22:00:00.032014 :901628C9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=189 time=3148389515 2025-04-28 22:00:00.032014 :901628CA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 22:00:00.032015 :901628CB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 22:00:00.032015 :901628CC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 22:00:00.032015 :901628CD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 22:00:00.032016 :901628CE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 22:00:00.032518 :901628CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.032573 :901628D6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:00.032649 :901628EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.036570 :901628F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.037805 :90162901:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.037828 :90162904:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.037867 :9016290F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:00.039638 :9016291A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.039664 :9016291D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.039704 :90162928:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:00.040171 :90162941:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.042654 :90162949:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.047984 :90162951:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.049109 :90162959:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.053890 :90162961:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.055326 :90162969:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.058269 :90162971:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.059554 :90162979:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.062566 :90162981:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.065922 :90162989:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.066285 :90162998:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.066349 :9016299B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-28 22:00:00.066371 :9016299C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-28 22:00:00.068386 :901629A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.068431 :901629A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-28 22:00:00.068442 :901629A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-28 22:00:00.070328 :901629AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.070370 :901629AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-04-28 22:00:00.070381 :901629B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-04-28 22:00:00.070557 :901629B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.070577 :901629B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:00:00.070637 :901629C9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:00.072166 :901629CF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 22:00:01.397884 :901629F5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 22:00:01.383 2025-04-28 22:00:01.397886 :901629F6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6948 pso_num=24 pso_serial#=206 2025-04-28 22:00:01.398019 :901629F7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6948 (legacy spawn) 2025-04-28 22:00:01.404544 :901629F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.404724 :901629FD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:01.405536 :90162A02:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 22:00:01.414213 :90162A05:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 22:00:01.399 2025-04-28 22:00:01.414216 :90162A06:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8236 pso_num=24 pso_serial#=207 2025-04-28 22:00:01.414352 :90162A07:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8236 (legacy spawn) 2025-04-28 22:00:01.420898 :90162A38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.421077 :90162A48:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:01.421860 :90162A49:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-28 22:00:01.431342 :90162A74:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-28 22:00:01.430 2025-04-28 22:00:01.431344 :90162A75:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8028 pso_num=24 pso_serial#=208 2025-04-28 22:00:01.431476 :90162A76:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8028 osp_idx=22 osp_ver=188397 osp_pg=0 (spawn #128388) 2025-04-28 22:00:01.431477 :90162A77:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 22:00:01.431478 :90162A78:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=190 time=3148390906 2025-04-28 22:00:01.431478 :90162A79:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 22:00:01.431478 :90162A7A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 22:00:01.431479 :90162A7B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 22:00:01.431479 :90162A7C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 22:00:01.431479 :90162A7D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 22:00:01.432020 :90162A87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.437134 :90162AA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.437172 :90162AA9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-04-28 22:00:01.448949 :90162B11:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-04-28 22:00:01.449522 :90162B2B:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 0daa110000000000 2025-04-28 22:00:01.451192 :90162B45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.451246 :90162B53:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.451254 :90162B59:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-04-28 22:00:01.451258 :90162B5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.451281 :90162B66:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.451935 :90162B72:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 223715355) 2025-04-28 22:00:01.451939 :90162B73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.451962 :90162B7E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.451969 :90162B8B:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1) 2025-04-28 22:00:01.451971 :90162B8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.451999 :90162B9C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.452003 :90162BA3:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560) 2025-04-28 22:00:01.452006 :90162BA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.452049 :90162BCC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.452642 :90162C75:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.452665 :90162C83:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-04-28 22:00:01.453589 :90162C8A:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 0eaa110000000000 2025-04-28 22:00:01.453968 :90162C8B:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 223715355) 2025-04-28 22:00:01.453974 :90162C8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.454000 :90162C97:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-28 22:00:01.478244 :90162D74:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-04-28 22:00:01.479855 :90162D75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.479905 :90162D78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.480035 :90162D85:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-28 22:00:01.555803 :90162D9E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 8028, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de