Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504082024\orcl_j001_8604_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504082024\orcl_j001_8604_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:37569M/63366M, Ph+PgF:45050M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 8604, image: ORACLE.EXE (J001) *** 2025-05-04 08:20:24.944 *** SESSION ID:(193.53619) 2025-05-04 08:20:24.944 *** 2025-05-04 08:20:24.944 Process diagnostic dump for ORACLE.EXE (J001), OS id=8604, pid: 24, proc_ser: 19, sid: 193, sess_ser: 53619 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8604 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.108951 sec, exc=0.108951 sec, total=0.108951 sec wait times: max=0.500000 sec, heur=17.645655 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.000015 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 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.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.510089 sec, exc=0.510089 sec, total=0.510089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.499984 sec, exc=0.499984 sec, total=0.499984 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 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.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=0.510107 sec, exc=0.510107 sec, total=0.510107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 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.000011 sec of elapsed time Sampled Session History of session 193 serial 53619 --------------------------------------------------- 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 --------------------------------------------------- [18 samples, 08:20:07 - 08:20:24] idle wait at each sample [session created at: 08:20:07] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-05-04 08:20:24.959 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 8604, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 08:06:06.060129*:927C6734:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002494BB58 2025-05-04 08:06:06.060666 :927C6735: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-05-04 08:06:06.074979 :927C673D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 08:06:06.075065 :927C673E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 08:06:06.075860 :927C673F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 08:06:06.076211 :927C6740: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-05-04 08:06:06.076249 :927C6743:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 08:06:06.084212 :927C6749: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-05-04 08:06:06.084281 :927C674C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-05-04 08:06:06.084323 :927C674D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-05-04 08:06:06.084681 :927C6753:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 08:06:06.085034 :927C6754:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 08:06:06.085225 :927C6755:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 08:06:06.085477 :927C6756: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-05-04 08:06:06.085503 :927C6759:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 08:06:06.087159 :927C675F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-04 08:06:06.087391 :927C6760: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-05-04 08:06:06.087420 :927C6763:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-04 08:06:06.088351 :927C6769: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-05-04 08:06:06.088478 :927C6772: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-05-04 08:06:36.168062 :927C7103:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:07:06.203675 :927C7AC3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:07:06.192 2025-05-04 08:07:06.203677 :927C7AC4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8696 pso_num=24 pso_serial#=6 2025-05-04 08:07:06.203810 :927C7AC5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8696 osp_idx=22 osp_ver=221600 osp_pg=0 (spawn #146773) 2025-05-04 08:07:06.203810 :927C7AC6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:07:06.203811 :927C7AC7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=50 time=3616803359 2025-05-04 08:07:06.203811 :927C7AC8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:07:06.203812 :927C7AC9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:07:06.203812 :927C7ACA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:07:06.203813 :927C7ACB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:07:06.203813 :927C7ACC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:07:06.204303 :927C7ACD: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-05-04 08:07:36.306178 :927C8487:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:08:06.300674 :927C8E2C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:08:06.289 2025-05-04 08:08:06.300676 :927C8E2D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11440 pso_num=24 pso_serial#=7 2025-05-04 08:08:06.300807 :927C8E2E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11440 osp_idx=22 osp_ver=221601 osp_pg=0 (spawn #146775) 2025-05-04 08:08:06.300807 :927C8E2F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:08:06.300808 :927C8E30:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=51 time=3616863453 2025-05-04 08:08:06.300809 :927C8E31:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:08:06.300809 :927C8E32:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:08:06.300811 :927C8E33:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:08:06.300811 :927C8E34:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:08:06.300812 :927C8E35:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:08:06.301292 :927C8E36: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-05-04 08:08:36.379234 :927C9871:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:09:06.388984 :927CA228:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:09:06.377 2025-05-04 08:09:06.388986 :927CA229:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7736 pso_num=24 pso_serial#=8 2025-05-04 08:09:06.389113 :927CA22A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7736 osp_idx=22 osp_ver=221602 osp_pg=0 (spawn #146777) 2025-05-04 08:09:06.389114 :927CA22B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:09:06.389114 :927CA22C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=52 time=3616923546 2025-05-04 08:09:06.389115 :927CA22D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:09:06.389115 :927CA22E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:09:06.389116 :927CA22F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:09:06.389116 :927CA230:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:09:06.389116 :927CA231:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:09:06.389654 :927CA232: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-05-04 08:09:36.470176 :927CAC15:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:10:06.460384 :927CB5F6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:10:06.449 2025-05-04 08:10:06.460386 :927CB5F7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8400 pso_num=24 pso_serial#=9 2025-05-04 08:10:06.460514 :927CB5F8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8400 osp_idx=22 osp_ver=221603 osp_pg=0 (spawn #146779) 2025-05-04 08:10:06.460515 :927CB5F9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:10:06.460515 :927CB5FA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=53 time=3616983625 2025-05-04 08:10:06.460516 :927CB5FB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:10:06.460516 :927CB5FC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:10:06.460517 :927CB5FD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:10:06.460517 :927CB5FE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:10:06.460517 :927CB5FF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:10:06.460984 :927CB600: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-05-04 08:10:36.539141 :927CBFAF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:11:06.545687 :927CC9CA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:11:06.534 2025-05-04 08:11:06.545689 :927CC9CB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11160 pso_num=24 pso_serial#=10 2025-05-04 08:11:06.545820 :927CC9CC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11160 osp_idx=22 osp_ver=221604 osp_pg=0 (spawn #146782) 2025-05-04 08:11:06.545820 :927CC9CD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:11:06.545821 :927CC9CE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=54 time=3617043703 2025-05-04 08:11:06.545821 :927CC9CF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:11:06.545822 :927CC9D0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:11:06.545822 :927CC9D1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:11:06.545823 :927CC9D2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:11:06.545823 :927CC9D3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:11:06.546297 :927CC9D4: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-05-04 08:11:36.662495 :927CD3AB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:12:06.629020 :927CDD59:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:12:06.617 2025-05-04 08:12:06.629022 :927CDD5A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8444 pso_num=24 pso_serial#=11 2025-05-04 08:12:06.629139 :927CDD5B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8444 osp_idx=22 osp_ver=221605 osp_pg=0 (spawn #146784) 2025-05-04 08:12:06.629140 :927CDD5C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:12:06.629140 :927CDD5D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=55 time=3617103781 2025-05-04 08:12:06.629140 :927CDD5E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:12:06.629141 :927CDD5F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:12:06.629141 :927CDD60:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:12:06.629142 :927CDD61:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:12:06.629142 :927CDD62:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:12:06.629643 :927CDD63: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-05-04 08:12:36.722149 :927CE70F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:13:06.708932 :927CF0CF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:13:06.697 2025-05-04 08:13:06.708935 :927CF0D0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11472 pso_num=24 pso_serial#=12 2025-05-04 08:13:06.709063 :927CF0D1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11472 osp_idx=22 osp_ver=221606 osp_pg=0 (spawn #146786) 2025-05-04 08:13:06.709064 :927CF0D2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:13:06.709064 :927CF0D3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=56 time=3617163875 2025-05-04 08:13:06.709065 :927CF0D4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:13:06.709065 :927CF0D5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:13:06.709066 :927CF0D6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:13:06.709066 :927CF0D7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:13:06.709067 :927CF0D8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:13:06.709545 :927CF0D9: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-05-04 08:13:36.775666 :927CFAF2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:14:06.765086 :927D04C2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:14:06.754 2025-05-04 08:14:06.765088 :927D04C3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9352 pso_num=24 pso_serial#=13 2025-05-04 08:14:06.765222 :927D04C4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9352 osp_idx=22 osp_ver=221607 osp_pg=0 (spawn #146788) 2025-05-04 08:14:06.765222 :927D04C5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:14:06.765223 :927D04C6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=57 time=3617223921 2025-05-04 08:14:06.765223 :927D04C7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:14:06.765224 :927D04C8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:14:06.765224 :927D04C9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:14:06.765224 :927D04CA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:14:06.765225 :927D04CB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:14:06.765756 :927D04CC: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-05-04 08:14:36.830113 :927D0E72:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:15:06.859854 :927D1856:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:15:06.848 2025-05-04 08:15:06.859857 :927D1857:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11620 pso_num=24 pso_serial#=14 2025-05-04 08:15:06.859989 :927D1858:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11620 osp_idx=22 osp_ver=221608 osp_pg=0 (spawn #146791) 2025-05-04 08:15:06.859990 :927D1859:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:15:06.859991 :927D185A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=58 time=3617284015 2025-05-04 08:15:06.859991 :927D185B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:15:06.859991 :927D185C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:15:06.859992 :927D185D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:15:06.859993 :927D185E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:15:06.859993 :927D185F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:15:06.860466 :927D1860: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-05-04 08:15:36.963965 :927D21F0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:16:01.944980 :927D2A54:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:16:01.934 2025-05-04 08:16:01.944982 :927D2A55:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11652 pso_num=24 pso_serial#=15 2025-05-04 08:16:01.945114 :927D2A56:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11652 osp_idx=22 osp_ver=221609 osp_pg=0 (spawn #146794) 2025-05-04 08:16:01.945115 :927D2A57:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:16:01.945115 :927D2A58:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=59 time=3617339109 2025-05-04 08:16:01.945116 :927D2A59:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:16:01.945116 :927D2A5A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:16:01.945117 :927D2A5B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:16:01.945117 :927D2A5C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:16:01.945117 :927D2A5D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:16:01.945595 :927D2A5E: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-05-04 08:16:06.941721 :927D2C2C: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-05-04 08:16:37.060827 :927D3606:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:17:07.023912 :927D3FB6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:17:07.012 2025-05-04 08:17:07.023913 :927D3FB7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10540 pso_num=24 pso_serial#=16 2025-05-04 08:17:07.024051 :927D3FB8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10540 osp_idx=22 osp_ver=221610 osp_pg=0 (spawn #146796) 2025-05-04 08:17:07.024052 :927D3FB9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:17:07.024052 :927D3FBA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=60 time=3617404187 2025-05-04 08:17:07.024052 :927D3FBB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:17:07.024053 :927D3FBC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:17:07.024053 :927D3FBD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:17:07.024053 :927D3FBE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:17:07.024054 :927D3FBF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:17:07.024565 :927D3FC0: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-05-04 08:17:37.133854 :927D494D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:18:07.111240 :927D5338:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:18:07.100 2025-05-04 08:18:07.111242 :927D5339:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8400 pso_num=24 pso_serial#=17 2025-05-04 08:18:07.111380 :927D533A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8400 osp_idx=22 osp_ver=221611 osp_pg=0 (spawn #146798) 2025-05-04 08:18:07.111381 :927D533B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:18:07.111382 :927D533C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=61 time=3617464265 2025-05-04 08:18:07.111382 :927D533D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:18:07.111383 :927D533E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:18:07.111383 :927D533F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:18:07.111383 :927D5340:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:18:07.111383 :927D5341:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:18:07.111847 :927D5342: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-05-04 08:18:37.212015 :927D5E20:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:19:07.217427 :927D6ADC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:19:07.206 2025-05-04 08:19:07.217429 :927D6ADD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9476 pso_num=24 pso_serial#=18 2025-05-04 08:19:07.217562 :927D6ADE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9476 osp_idx=22 osp_ver=221612 osp_pg=0 (spawn #146800) 2025-05-04 08:19:07.217563 :927D6ADF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:19:07.217563 :927D6AE0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=62 time=3617524375 2025-05-04 08:19:07.217564 :927D6AE1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:19:07.217564 :927D6AE2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:19:07.217565 :927D6AE3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:19:07.217565 :927D6AE4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:19:07.217565 :927D6AE5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:19:07.218120 :927D6AE6: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-05-04 08:19:37.331015 :927D7499:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-04 08:20:07.312865 :927D7DDB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-04 08:20:07.301 2025-05-04 08:20:07.312868 :927D7DDC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8604 pso_num=24 pso_serial#=19 2025-05-04 08:20:07.313068 :927D7DDD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8604 osp_idx=22 osp_ver=221613 osp_pg=0 (spawn #146802) 2025-05-04 08:20:07.313069 :927D7DDE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:20:07.313069 :927D7DDF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=63 time=3617584468 2025-05-04 08:20:07.313070 :927D7DE0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:20:07.313071 :927D7DE1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:20:07.313071 :927D7DE2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:20:07.313072 :927D7DE3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:20:07.313072 :927D7DE4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:20:07.313570 :927D7DE5: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 8604, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de