Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250521220008\orcl_j002_10716_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250521220008\orcl_j002_10716_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:37320M/63366M, Ph+PgF:44397M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 10716, image: ORACLE.EXE (J002) *** 2025-05-21 22:00:08.621 *** SESSION ID:(225.3601) 2025-05-21 22:00:08.621 *** 2025-05-21 22:00:08.621 Process diagnostic dump for ORACLE.EXE (J002), OS id=10716, pid: 28, proc_ser: 124, sid: 225, sess_ser: 3601 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10716 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.098265 sec, exc=0.098265 sec, total=0.098265 sec wait times: max=0.500000 sec, heur=6.543258 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.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.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510093 sec, exc=0.510093 sec, total=0.510093 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.509366 sec, exc=0.509366 sec, total=0.509366 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.323341 sec, exc=0.323341 sec, total=0.323341 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 225 serial 3601 --------------------------------------------------- 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:02 - 22:00:08] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-21 22:00:08.621 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 10716, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-21 15:30:46.206915 :9A1E13DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 15:30:46.206931 :9A1E13E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 15:30:46.207142 :9A1E13E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 15:30:46.207155 :9A1E13E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 15:30:46.207367 :9A1E13E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 15:30:46.207380 :9A1E13E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 15:30:46.207583 :9A1E13E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 15:30:46.207596 :9A1E13E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 15:30:46.207798 :9A1E13E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 15:30:46.207810 :9A1E13E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 15:30:46.210447 :9A1E13E9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:12.835736 :9A204C7D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:12.822 2025-05-21 16:00:12.835739 :9A204C7E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13092 pso_num=28 pso_serial#=114 2025-05-21 16:00:12.835873 :9A204C7F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13092 (legacy spawn) 2025-05-21 16:00:12.842362 :9A204C80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 16:00:12.842529 :9A204C85:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 16:00:12.843340 :9A204C8A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:18.008381 :9A204E06:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:17.993 2025-05-21 16:00:18.008383 :9A204E07:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13204 pso_num=28 pso_serial#=115 2025-05-21 16:00:18.008526 :9A204E08:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13204 (legacy spawn) 2025-05-21 16:00:18.014982 :9A204E15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 16:00:18.015152 :9A204E1A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 16:00:18.015927 :9A204E1F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:18.024556 :9A204E22:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:18.009 2025-05-21 16:00:18.024557 :9A204E23:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11704 pso_num=28 pso_serial#=116 2025-05-21 16:00:18.024694 :9A204E24:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11704 (legacy spawn) 2025-05-21 16:00:18.031095 :9A204E25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 16:00:18.031266 :9A204E2E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 16:00:18.032120 :9A204E2F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:21.182879 :9A204F12:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:21.175 2025-05-21 16:00:21.182882 :9A204F13:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13716 pso_num=28 pso_serial#=117 2025-05-21 16:00:21.183026 :9A204F14:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13716 (legacy spawn) 2025-05-21 16:00:21.189501 :9A204F15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 16:00:21.189683 :9A204F1A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 16:00:21.190477 :9A204F1F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:21.199041 :9A204F22:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:21.191 2025-05-21 16:00:21.199043 :9A204F23:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11548 pso_num=28 pso_serial#=118 2025-05-21 16:00:21.199180 :9A204F24:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11548 (legacy spawn) 2025-05-21 16:00:21.205644 :9A204F25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 16:00:21.205807 :9A204F2E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 16:00:21.206607 :9A204F2F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:24.418184 :9A20510E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:24.409 2025-05-21 16:00:24.418186 :9A20510F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12472 pso_num=28 pso_serial#=119 2025-05-21 16:00:24.418319 :9A205110:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12472 (legacy spawn) 2025-05-21 16:00:24.424778 :9A205111:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 16:00:24.424949 :9A205116:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 16:00:24.425785 :9A20511B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 16:00:50.367583 :9A205922:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 16:00:50.355 2025-05-21 16:00:50.367585 :9A205923:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13168 pso_num=28 pso_serial#=120 2025-05-21 16:00:50.367719 :9A205924:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13168 osp_idx=26 osp_ver=132718 osp_pg=0 (spawn #205011) 2025-05-21 16:00:50.367722 :9A205925:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-21 16:00:50.367723 :9A205926:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=126 time=819060235 2025-05-21 16:00:50.367723 :9A205927:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-21 16:00:50.367723 :9A205928:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-21 16:00:50.367723 :9A205929:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-21 16:00:50.367724 :9A20592A:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-21 16:00:50.367724 :9A20592B:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-21 16:00:50.368284 :9A20592C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-21 16:00:50.368322 :9A205932:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-21 16:00:50.372375 :9A205945:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 16:00:50.372425 :9A205946:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 16:00:50.372681 :9A205947:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 16:00:50.372696 :9A205948:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 16:00:50.372923 :9A205949:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 16:00:50.372936 :9A20594A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 16:00:50.373147 :9A20594B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 16:00:50.373160 :9A20594C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 16:00:50.373366 :9A205957:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 16:00:50.373379 :9A205958:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 16:00:50.373589 :9A205959:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-21 16:00:50.373602 :9A20595A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-21 16:00:50.376249 :9A205977:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 17:00:58.403692 :9A24ED78:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 17:00:58.394 2025-05-21 17:00:58.403694 :9A24ED79:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12832 pso_num=28 pso_serial#=121 2025-05-21 17:00:58.403829 :9A24ED7A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12832 osp_idx=26 osp_ver=132719 osp_pg=0 (spawn #205151) 2025-05-21 17:00:58.403830 :9A24ED7B:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-21 17:00:58.403830 :9A24ED7C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=127 time=822668266 2025-05-21 17:00:58.403830 :9A24ED7D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-21 17:00:58.403831 :9A24ED7E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-21 17:00:58.403831 :9A24ED7F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-21 17:00:58.403831 :9A24ED80:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-21 17:00:58.403832 :9A24ED81:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-21 17:00:58.404293 :9A24ED85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-21 17:00:58.404336 :9A24ED8B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-21 17:00:58.413376 :9A24EDA9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 17:57:52.867287 :9A294146:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 17:57:52.855 2025-05-21 17:57:52.867288 :9A294147:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12136 pso_num=28 pso_serial#=122 2025-05-21 17:57:52.867407 :9A294148:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12136 (legacy spawn) 2025-05-21 17:57:52.874375 :9A29414C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 17:57:52.874535 :9A294155:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 17:57:52.875315 :9A294156:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 22:00:00.049753 :9A3BAFF0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 22:00:00.039 2025-05-21 22:00:00.049755 :9A3BAFF1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10096 pso_num=28 pso_serial#=123 2025-05-21 22:00:00.049892 :9A3BAFF2:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10096 osp_idx=26 osp_ver=132721 osp_pg=0 (spawn #205840) 2025-05-21 22:00:00.049892 :9A3BAFF3:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-21 22:00:00.049893 :9A3BAFF4:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=128 time=840609907 2025-05-21 22:00:00.049893 :9A3BAFF5:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-21 22:00:00.049894 :9A3BAFF6:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-21 22:00:00.049894 :9A3BAFF7:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-21 22:00:00.049894 :9A3BAFF8:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-21 22:00:00.049895 :9A3BAFF9:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-21 22:00:00.050389 :9A3BAFFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.050431 :9A3BB001:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-05-21 22:00:00.050507 :9A3BB015:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.054327 :9A3BB024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.055496 :9A3BB02C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.055524 :9A3BB02F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.055573 :9A3BB03A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:00.057339 :9A3BB045:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.057366 :9A3BB048:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.057403 :9A3BB053:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:00.057952 :9A3BB05E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.060306 :9A3BB066:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.065584 :9A3BB06E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.071093 :9A3BB076:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.072438 :9A3BB07E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.075405 :9A3BB086:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.076650 :9A3BB08E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.079595 :9A3BB096:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.082966 :9A3BB09E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.083413 :9A3BB0AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.083463 :9A3BB0B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-05-21 22:00:00.083477 :9A3BB0B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-05-21 22:00:00.085472 :9A3BB0B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.085516 :9A3BB0BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-05-21 22:00:00.085527 :9A3BB0BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-05-21 22:00:00.087362 :9A3BB0C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.087402 :9A3BB0C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-05-21 22:00:00.087413 :9A3BB0C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-05-21 22:00:00.087581 :9A3BB0CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.087596 :9A3BB0CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-21 22:00:00.087636 :9A3BB0DA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:00.089205 :9A3BB0E4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-21 22:00:02.055020 :9A3BB221:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-21 22:00:02.049 2025-05-21 22:00:02.055022 :9A3BB222:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10716 pso_num=28 pso_serial#=124 2025-05-21 22:00:02.055162 :9A3BB223:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10716 osp_idx=26 osp_ver=132722 osp_pg=0 (spawn #205841) 2025-05-21 22:00:02.055163 :9A3BB224:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-21 22:00:02.055163 :9A3BB225:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=129 time=840611922 2025-05-21 22:00:02.055164 :9A3BB226:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-21 22:00:02.055164 :9A3BB227:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-21 22:00:02.055165 :9A3BB228:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-21 22:00:02.055165 :9A3BB229:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-21 22:00:02.055165 :9A3BB22A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-21 22:00:02.055687 :9A3BB22F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.060929 :9A3BB267:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.060967 :9A3BB268:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-05-21 22:00:02.066011 :9A3BB299:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-21 22:00:02.066599 :9A3BB2A3:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 e6d7110000000000 2025-05-21 22:00:02.068332 :9A3BB2CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.068408 :9A3BB2D9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.068417 :9A3BB2DF:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-05-21 22:00:02.068423 :9A3BB2E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.068446 :9A3BB2EC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.069090 :9A3BB2F8:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 225999816) 2025-05-21 22:00:02.069094 :9A3BB2F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.069117 :9A3BB305:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.069125 :9A3BB311:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65564 1) 2025-05-21 22:00:02.069128 :9A3BB312:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.069159 :9A3BB31E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.069163 :9A3BB326:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65564) 2025-05-21 22:00:02.069165 :9A3BB32B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.069212 :9A3BB34A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.070053 :9A3BB3F7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.070076 :9A3BB406:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-21 22:00:02.070974 :9A3BB40B:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 e7d7110000000000 2025-05-21 22:00:02.071345 :9A3BB40C:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 225999816) 2025-05-21 22:00:02.071352 :9A3BB40D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.071379 :9A3BB418:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-21 22:00:02.079520 :9A3BB472:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-05-21 22:00:02.081289 :9A3BB47C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.081332 :9A3BB481:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-21 22:00:02.081442 :9A3BB48E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 22:00:02.175131 :9A3BB5B8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-21 22:00:04.007655 :9A3BBAF0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 10716, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de