Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250506220010\orcl_j004_11708_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250506220010\orcl_j004_11708_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:37459M/63366M, Ph+PgF:44939M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 11708, image: ORACLE.EXE (J004) *** 2025-05-06 22:00:10.245 *** SESSION ID:(241.1455) 2025-05-06 22:00:10.245 *** 2025-05-06 22:00:10.245 Process diagnostic dump for ORACLE.EXE (J004), OS id=11708, pid: 30, proc_ser: 203, sid: 241, sess_ser: 1455 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11708 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.200425 sec, exc=0.200425 sec, total=0.200425 sec wait times: max=0.500000 sec, heur=6.208706 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 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=10 seq_num=11 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.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499965 sec, exc=0.499965 sec, total=0.499965 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510139 sec, exc=0.510139 sec, total=0.510139 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000012 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 241 serial 1455 --------------------------------------------------- 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:04 - 22:00:10] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-06 22:00:10.245 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 11708, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-06 00:05:03.609989 :933AC9ED:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11236 pso_num=30 pso_serial#=192 2025-05-06 00:05:03.610130 :933AC9EE:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11236 (legacy spawn) 2025-05-06 00:05:03.616614 :933AC9EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:05:03.616793 :933AC9F4:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:05:03.617591 :933AC9F9:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:05:03.626279 :933AC9FC:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:05:03.612 2025-05-06 00:05:03.626281 :933AC9FD:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9076 pso_num=30 pso_serial#=193 2025-05-06 00:05:03.626423 :933AC9FE:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9076 (legacy spawn) 2025-05-06 00:05:03.632927 :933AC9FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:05:03.633086 :933ACA08:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:05:03.633885 :933ACA09:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:05:51.361503 :933ADEE3:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:05:51.353 2025-05-06 00:05:51.361506 :933ADEE4:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9676 pso_num=30 pso_serial#=194 2025-05-06 00:05:51.361675 :933ADEE5:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9676 (legacy spawn) 2025-05-06 00:05:51.368204 :933ADEE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:05:51.368383 :933ADEEB:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:05:51.369240 :933ADEF0:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:06:04.827065 :933AE46B:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:06:04.822 2025-05-06 00:06:04.827067 :933AE46C:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=6788 pso_num=30 pso_serial#=195 2025-05-06 00:06:04.827199 :933AE46D:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=6788 osp_idx=28 osp_ver=704 osp_pg=0 (spawn #152426) 2025-05-06 00:06:04.827200 :933AE46E:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 00:06:04.827201 :933AE46F:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=65 time=3760742000 2025-05-06 00:06:04.827201 :933AE470:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 00:06:04.827201 :933AE471:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 00:06:04.827202 :933AE472:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 00:06:04.827202 :933AE473:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 00:06:04.827202 :933AE474:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 00:06:04.827691 :933AE475:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 00:06:04.827737 :933AE47B:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:06:04.848586 :933AE4E8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:06:06.586504 :933AE54F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:06:06.572 2025-05-06 00:06:06.586506 :933AE550:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=6832 pso_num=30 pso_serial#=196 2025-05-06 00:06:06.586679 :933AE551:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=6832 (legacy spawn) 2025-05-06 00:06:06.593425 :933AE552:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:06:06.593620 :933AE557:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:06:06.594519 :933AE55C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:06:13.485271 :933AE773:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:06:13.471 2025-05-06 00:06:13.485273 :933AE774:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12092 pso_num=30 pso_serial#=197 2025-05-06 00:06:13.485409 :933AE775:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=12092 (legacy spawn) 2025-05-06 00:06:13.491923 :933AE776:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:06:13.492115 :933AE77B:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:06:13.492930 :933AE780:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:06:13.501461 :933AE783:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:06:13.487 2025-05-06 00:06:13.501463 :933AE784:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10296 pso_num=30 pso_serial#=198 2025-05-06 00:06:13.501600 :933AE785:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=10296 (legacy spawn) 2025-05-06 00:06:13.508009 :933AE786:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:06:13.508177 :933AE78F:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:06:13.508957 :933AE790:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:06:16.703615 :933AE87F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:06:16.691 2025-05-06 00:06:16.703618 :933AE880:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9580 pso_num=30 pso_serial#=199 2025-05-06 00:06:16.703753 :933AE881:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9580 (legacy spawn) 2025-05-06 00:06:16.710333 :933AE882:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:06:16.710513 :933AE887:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:06:16.711348 :933AE88C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 00:06:16.720185 :933AE88F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 00:06:16.706 2025-05-06 00:06:16.720188 :933AE890:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=12040 pso_num=30 pso_serial#=200 2025-05-06 00:06:16.720329 :933AE891:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=12040 (legacy spawn) 2025-05-06 00:06:16.726940 :933AE892:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:06:16.726972 :933AE895:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:06:16.727140 :933AE89C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 00:06:16.728068 :933AE8A9:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 01:00:13.971545 :933F1447:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 01:00:13.964 2025-05-06 01:00:13.971547 :933F1448:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11928 pso_num=30 pso_serial#=201 2025-05-06 01:00:13.971688 :933F1449:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11928 osp_idx=28 osp_ver=710 osp_pg=0 (spawn #152552) 2025-05-06 01:00:13.971689 :933F144A:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 01:00:13.971689 :933F144B:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=66 time=3763991140 2025-05-06 01:00:13.971689 :933F144C:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 01:00:13.971690 :933F144D:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 01:00:13.971690 :933F144E:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 01:00:13.971691 :933F144F:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 01:00:13.971691 :933F1450:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 01:00:13.972196 :933F1451:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.972241 :933F1457:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 01:00:13.979530 :933F1465:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.980582 :933F146D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.981135 :933F1475:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.981584 :933F147D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.982009 :933F1485:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.984507 :933F148D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:13.985563 :933F1495:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:14.034687 :933F149D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:14.035558 :933F14A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:14.035831 :933F14AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:14.036096 :933F14B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 01:00:14.037786 :933F14BD:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 08:22:02.365142 :93614E84:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 08:22:02.371 2025-05-06 08:22:02.365144 :93614E85:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11548 pso_num=30 pso_serial#=202 2025-05-06 08:22:02.365280 :93614E86:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11548 osp_idx=28 osp_ver=711 osp_pg=0 (spawn #153594) 2025-05-06 08:22:02.365280 :93614E87:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:22:02.365281 :93614E88:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=67 time=3790499531 2025-05-06 08:22:02.365281 :93614E89:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:22:02.365281 :93614E8A:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:22:02.365282 :93614E8B:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:22:02.365282 :93614E8C:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:22:02.365283 :93614E8D:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:22:02.365791 :93614E90:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:22:02.365835 :93614E98:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-06 08:22:02.372684 :93614EA8:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-06 22:00:02.113199 :93A099D0:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-06 22:00:02.111 2025-05-06 22:00:02.113199 :93A099D1:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11708 pso_num=30 pso_serial#=203 2025-05-06 22:00:02.113326 :93A099D9:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11708 osp_idx=28 osp_ver=712 osp_pg=0 (spawn #155510) 2025-05-06 22:00:02.113326 :93A099DA:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 22:00:02.113327 :93A099DB:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=68 time=3839579265 2025-05-06 22:00:02.113327 :93A099DC:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 22:00:02.113328 :93A099DD:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 22:00:02.113328 :93A099DE:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 22:00:02.113329 :93A099DF:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 22:00:02.113329 :93A099E0:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 22:00:02.113869 :93A099EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 22:00:02.118839 :93A09A09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:02.118866 :93A09A0C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-05-06 22:00:02.124190 :93A09B7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:02.353797 :93A09CDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:02.561794 :93A09D08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:02.700549 :93A09D23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.836237 :93A09ED8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.837224 :93A09EE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.841220 :93A09EE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.843653 :93A09EF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.845892 :93A09EF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.847526 :93A09F08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.848889 :93A09F10:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.870696 :93A09F47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.871275 :93A09F4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.871852 :93A09F57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.872348 :93A09F5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.875166 :93A09F67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.876163 :93A09F6F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.876914 :93A09F7C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.877707 :93A09F7D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.877707*:93A09F7E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000319387C0 estart=1746540003 eid=16784907 ctx=0x000000079FF419C0 cwh=0x0000000031702158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-06 22:00:03.877707*:93A09F7F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000319387C0 curCtx=0x000000003177EEA8 pubTabIdxCnt=0 2025-05-06 22:00:03.877707*:93A09F80:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000319387C0 2025-05-06 22:00:03.877707*:93A09F81:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000319387C0 2025-05-06 22:00:03.877707*:93A09F82:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000316FBC50 estart=1746540002 eid=16777307 ctx=0x0000000776DBD990 cwh=0x000000003169BFD0 cpu=15625 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-06 22:00:03.877707*:93A09F83:sql_mon_query:keswx.c@6118:keswxCurPushMonitoring(): disable monitoring for xsc=0x00000000316FBC50 curCtx=0x0000000000000000 reason: quick check failed 2025-05-06 22:00:03.877707*:93A09F84:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000316FBC50 2025-05-06 22:00:03.877707*:93A09F85:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000319387C0 curCtx=0x000000003177EEA8 xsc->flg4=65792 flags=1 2025-05-06 22:00:03.877707*:93A09F86:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000319387C0 2025-05-06 22:00:03.878196 :93A09F87:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.878203 :93A09F88:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.878779 :93A09F89:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.879270 :93A09F8A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.879543 :93A09F8B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.880729 :93A09F8C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.917079 :93A09F8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.917124 :93A09F92:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.918195 :93A09F98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.919142 :93A09FA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.919606 :93A09FA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.940081 :93A09FAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.940133 :93A09FAE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.940983 :93A09FC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.952579 :93A09FCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.953155 :93A09FCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.972454 :93A09FD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.972501 :93A09FD6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.973462 :93A09FDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.974287 :93A09FE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.974762 :93A09FE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.992307 :93A09FF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.992359 :93A09FFA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:03.993247 :93A0A000:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:03.994195 :93A0A008:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:03.994647 :93A0A009:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.018097 :93A0A030:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.018141 :93A0A033:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:04.018962 :93A0A039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.019953 :93A0A041:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-06 22:00:04.020399 :93A0A042:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.040348 :93A0A074:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.040398 :93A0A077:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-06 22:00:04.041360 :93A0A07D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.041832 :93A0A085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.042206 :93A0A08D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.042378 :93A0A095:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.042518 :93A0A09D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.042691 :93A0A0A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-05-06 22:00:04.046654 :93A0A0A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.046754 :93A0A0A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-06 22:00:04.046830 :93A0A0B6:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 11708, J004)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de