Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250806220007\orcl_j003_16860_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250806220007\orcl_j003_16860_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:35657M/63366M, Ph+PgF:41167M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 16860, image: ORACLE.EXE (J003) *** 2025-08-06 22:00:07.607 *** SESSION ID:(233.31189) 2025-08-06 22:00:07.607 *** 2025-08-06 22:00:07.607 Process diagnostic dump for ORACLE.EXE (J003), OS id=16860, pid: 29, proc_ser: 231, sid: 233, sess_ser: 31189 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16860 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) 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.262904 sec, exc=0.262904 sec, total=0.262904 sec wait times: max=0.500000 sec, heur=6.784556 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.000009 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.505666 sec, exc=0.505666 sec, total=0.505666 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.510146 sec, exc=0.510146 sec, total=0.510146 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 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.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.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.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.000006 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.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.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.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.000007 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.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.000009 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.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.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.000010 sec of elapsed time Sampled Session History of session 233 serial 31189 --------------------------------------------------- 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:00 - 22:00:07] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-06 22:00:07.607 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 16860, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-06 00:05:33.729824 :BBE1D3B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-06 00:05:33.730037 :BBE1D3B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-06 00:05:33.730050 :BBE1D3B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-06 00:05:33.730258 :BBE1D3BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-06 00:05:33.730271 :BBE1D3BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-06 00:05:33.730476 :BBE1D3BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-06 00:05:33.730490 :BBE1D3BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-06 00:05:33.732887 :BBE1D3BE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:14.350965 :BBE61E71:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:14.342 2025-08-06 01:00:14.350967 :BBE61E72:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15600 pso_num=29 pso_serial#=218 2025-08-06 01:00:14.351108 :BBE61E73:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15600 (legacy spawn) 2025-08-06 01:00:14.357725 :BBE61E74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:14.357931 :BBE61E79:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:14.358826 :BBE61E7E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:15.407671 :BBE61EC8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:15.399 2025-08-06 01:00:15.407673 :BBE61EC9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21224 pso_num=29 pso_serial#=219 2025-08-06 01:00:15.407811 :BBE61ECA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21224 (legacy spawn) 2025-08-06 01:00:15.414227 :BBE61ECB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:15.414390 :BBE61ED4:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:15.415230 :BBE61ED5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:16.013199 :BBE61F52:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:16.008 2025-08-06 01:00:16.013202 :BBE61F53:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21200 pso_num=29 pso_serial#=220 2025-08-06 01:00:16.013340 :BBE61F54:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21200 osp_idx=27 osp_ver=15519 osp_pg=0 (spawn #459572) 2025-08-06 01:00:16.013340 :BBE61F55:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 01:00:16.013341 :BBE61F56:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=165 time=3122685314 2025-08-06 01:00:16.013341 :BBE61F57:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 01:00:16.013341 :BBE61F58:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 01:00:16.013342 :BBE61F59:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 01:00:16.013342 :BBE61F5A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 01:00:16.013342 :BBE61F5B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 01:00:16.013830 :BBE61F5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.013875 :BBE61F62:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:16.018397 :BBE61F75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.042017 :BBE61F7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.057691 :BBE61F85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.089491 :BBE61F8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.093221 :BBE61F95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.096937 :BBE61F9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.101259 :BBE61FA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:16.102371 :BBE61FAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:17.318788 :BBE61FC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:17.360744 :BBE62007:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:17.361057 :BBE6200F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:17.361339 :BBE62017:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:17.361356 :BBE6201A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:17.361419 :BBE62026:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:17.364444 :BBE62034:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:20.949032 :BBE621D9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:20.940 2025-08-06 01:00:20.949034 :BBE621DA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14940 pso_num=29 pso_serial#=221 2025-08-06 01:00:20.949175 :BBE621DB:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14940 (legacy spawn) 2025-08-06 01:00:20.955733 :BBE621DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:20.955904 :BBE621E5:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:20.956727 :BBE621E6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:22.298408 :BBE62253:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:22.283 2025-08-06 01:00:22.298410 :BBE62254:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15304 pso_num=29 pso_serial#=222 2025-08-06 01:00:22.298571 :BBE62255:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15304 (legacy spawn) 2025-08-06 01:00:22.305393 :BBE62256:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:22.305591 :BBE6225B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:22.306519 :BBE62260:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:22.315981 :BBE62263:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:22.314 2025-08-06 01:00:22.315983 :BBE62264:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21228 pso_num=29 pso_serial#=223 2025-08-06 01:00:22.316128 :BBE62265:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21228 (legacy spawn) 2025-08-06 01:00:22.323158 :BBE62266:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:22.323335 :BBE6226F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:22.324252 :BBE62270:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:25.424423 :BBE62363:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:25.411 2025-08-06 01:00:25.424425 :BBE62364:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19036 pso_num=29 pso_serial#=224 2025-08-06 01:00:25.424562 :BBE62365:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19036 (legacy spawn) 2025-08-06 01:00:25.431098 :BBE62366:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:25.431276 :BBE6236D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:25.432075 :BBE62370:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 01:00:25.440727 :BBE62373:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 01:00:25.427 2025-08-06 01:00:25.440729 :BBE62374:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21012 pso_num=29 pso_serial#=225 2025-08-06 01:00:25.440871 :BBE62375:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21012 (legacy spawn) 2025-08-06 01:00:25.447407 :BBE62376:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 01:00:25.447597 :BBE6237F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 01:00:25.448444 :BBE62380:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 08:29:16.990935 :BC09B204:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 08:29:16.978 2025-08-06 08:29:16.990938 :BC09B205:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19536 pso_num=29 pso_serial#=226 2025-08-06 08:29:16.993989 :BC09B206:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19536 osp_idx=27 osp_ver=15525 osp_pg=0 (spawn #460611) 2025-08-06 08:29:16.993991 :BC09B207:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:29:16.993991 :BC09B208:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=166 time=3149626267 2025-08-06 08:29:16.993992 :BC09B209:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:29:16.993992 :BC09B20A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:29:16.993993 :BC09B20B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:29:16.993993 :BC09B20C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:29:16.993993 :BC09B20D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:29:16.999119 :BC09B20E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 08:29:16.999176 :BC09B214:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 08:29:16.999630 :BC09B215:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 08:29:19.117061 :BC09B330:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 08:29:19.113 2025-08-06 08:29:19.117062 :BC09B331:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20788 pso_num=29 pso_serial#=227 2025-08-06 08:29:19.117212 :BC09B332:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20788 osp_idx=27 osp_ver=15526 osp_pg=0 (spawn #460613) 2025-08-06 08:29:19.117213 :BC09B333:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 08:29:19.117213 :BC09B334:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=167 time=3149628408 2025-08-06 08:29:19.117214 :BC09B335:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 08:29:19.117214 :BC09B336:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 08:29:19.117214 :BC09B337:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 08:29:19.117214 :BC09B338:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 08:29:19.117215 :BC09B339:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 08:29:19.117732 :BC09B33A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 08:29:19.117770 :BC09B340:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 08:29:19.118137 :BC09B341:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 16:00:07.087190 :BC2D21DF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 16:00:07.073 2025-08-06 16:00:07.087192 :BC2D21E0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18512 pso_num=29 pso_serial#=228 2025-08-06 16:00:07.087326 :BC2D21E1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18512 (legacy spawn) 2025-08-06 16:00:07.093816 :BC2D21E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.093846 :BC2D21E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.094197 :BC2D21E8:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 16:00:07.094992 :BC2D21F9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 16:00:07.104047 :BC2D21FC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 16:00:07.089 2025-08-06 16:00:07.104050 :BC2D21FD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19304 pso_num=29 pso_serial#=229 2025-08-06 16:00:07.104196 :BC2D21FE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19304 (legacy spawn) 2025-08-06 16:00:07.110826 :BC2D2201:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.110991 :BC2D220A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 16:00:07.111809 :BC2D220B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 16:00:07.173645 :BC2D2252:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 16:00:07.167 2025-08-06 16:00:07.173647 :BC2D2253:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20460 pso_num=29 pso_serial#=230 2025-08-06 16:00:07.173786 :BC2D2254:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20460 osp_idx=27 osp_ver=15529 osp_pg=0 (spawn #461655) 2025-08-06 16:00:07.173787 :BC2D2255:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 16:00:07.173787 :BC2D2256:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=168 time=3176676470 2025-08-06 16:00:07.173788 :BC2D2257:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 16:00:07.173788 :BC2D2258:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 16:00:07.173789 :BC2D2259:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 16:00:07.173789 :BC2D225A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 16:00:07.173789 :BC2D225B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 16:00:07.174315 :BC2D225C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.174360 :BC2D2262:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-06 16:00:07.181658 :BC2D2270:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.182760 :BC2D2278:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.183336 :BC2D2280:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.183792 :BC2D2288:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.184222 :BC2D2290:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.186826 :BC2D2298:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.187945 :BC2D22A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.242956 :BC2D22A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.243975 :BC2D22B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.244263 :BC2D22B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.244610 :BC2D22C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 16:00:07.246689 :BC2D22C8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-06 22:00:00.805419 :BC493E68:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-06 22:00:00.798 2025-08-06 22:00:00.805421 :BC493E6A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16860 pso_num=29 pso_serial#=231 2025-08-06 22:00:00.805565 :BC493E6E:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16860 osp_idx=27 osp_ver=15530 osp_pg=0 (spawn #462485) 2025-08-06 22:00:00.805566 :BC493E6F:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-06 22:00:00.805567 :BC493E70:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=169 time=3198270111 2025-08-06 22:00:00.805567 :BC493E71:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-06 22:00:00.805568 :BC493E72:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-06 22:00:00.805568 :BC493E73:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-06 22:00:00.805568 :BC493E74:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-06 22:00:00.805569 :BC493E75:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-06 22:00:00.806106 :BC493E83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.811577 :BC493E9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.811632 :BC493E9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-06 22:00:00.816568 :BC493EE1:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-06 22:00:00.817253 :BC493EEB:db_trace:kcc.c@13684:kccwbp(): [10021:29:233:1] 0115000000000000 0115000000000000 7171120000000000 2025-08-06 22:00:00.819023 :BC493F16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.819114 :BC493F2A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.819125 :BC493F31:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-08-06 22:00:00.819132 :BC493F32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.819165 :BC493F3E:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.819918 :BC493F4A:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 233654560) 2025-08-06 22:00:00.819923 :BC493F4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.819950 :BC493F57:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.819958 :BC493F63:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65565 1) 2025-08-06 22:00:00.819961 :BC493F64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.819990 :BC493F70:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.819994 :BC493F77:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65565) 2025-08-06 22:00:00.819997 :BC493F7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.820039 :BC493F98:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.821208 :BC49402F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.821224 :BC494034:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-06 22:00:00.822133 :BC494043:db_trace:kcc.c@13684:kccwbp(): [10021:29:233:1] 0115000000000000 0115000000000000 7271120000000000 2025-08-06 22:00:00.822577 :BC49404C:db_trace:ksb.c@6610:ksbcic_int(): [10254:29:233] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 233654560) 2025-08-06 22:00:00.822584 :BC49404D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.822613 :BC494056:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-06 22:00:00.831753 :BC4940CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-08-06 22:00:00.832717 :BC4940CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.833098 :BC4940E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.833138 :BC4940E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.833174 :BC4940E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-06 22:00:00.833269 :BC4940F7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 16860, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de