Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250911220004\orcl_j003_23240_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250911220004\orcl_j003_23240_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:34852M/63366M, Ph+PgF:39549M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 23240, image: ORACLE.EXE (J003) *** 2025-09-11 22:00:05.017 *** SESSION ID:(233.34939) 2025-09-11 22:00:05.017 *** 2025-09-11 22:00:05.017 Process diagnostic dump for ORACLE.EXE (J003), OS id=23240, pid: 29, proc_ser: 52, sid: 233, sess_ser: 34939 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23240 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13062, hash value=3739485894 Current Wait Stack: Not in wait; last wait ended 2.811894 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.811920 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000181 sec, exc=0.000181 sec, total=0.000181 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.067631 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.757843 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025658 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.013188 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000073 sec, exc=0.000073 sec, total=0.000073 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.050400 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.003218 sec, exc=0.003218 sec, total=0.003218 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008112 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000124 sec, exc=0.000124 sec, total=0.000124 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.264270 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.006484 sec, exc=0.006484 sec, total=0.006484 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000169 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000121 sec, exc=0.000121 sec, total=0.000121 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008100 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000053 sec of elapsed time Sampled Session History of session 233 serial 34939 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:00 - 22:00:04] not in wait at each sample [1 sample, 21:59:59] 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-09-11 22:00:05.017 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 23240, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-11 00:05:05.335707 :CBBDE64F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23544 pso_num=29 pso_serial#=37 2025-09-11 00:05:05.335848 :CBBDE650:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23544 (legacy spawn) 2025-09-11 00:05:05.342681 :CBBDE651: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-09-11 00:05:05.342863 :CBBDE656: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-09-11 00:05:05.343726 :CBBDE65B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:05:59.639955 :CBBDFE54:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:05:59.630 2025-09-11 00:05:59.639957 :CBBDFE55:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22724 pso_num=29 pso_serial#=38 2025-09-11 00:05:59.640091 :CBBDFE56:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22724 (legacy spawn) 2025-09-11 00:05:59.646650 :CBBDFE57: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-09-11 00:05:59.646824 :CBBDFE5C: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-09-11 00:05:59.647645 :CBBDFE61:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:05.287066 :CBBE003E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:05.272 2025-09-11 00:06:05.287068 :CBBE003F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22252 pso_num=29 pso_serial#=39 2025-09-11 00:06:05.287208 :CBBE0040:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22252 (legacy spawn) 2025-09-11 00:06:05.293634 :CBBE0041: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-09-11 00:06:05.293804 :CBBE0049: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-09-11 00:06:05.294596 :CBBE004B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:05.303355 :CBBE004E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:05.288 2025-09-11 00:06:05.303357 :CBBE004F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22932 pso_num=29 pso_serial#=40 2025-09-11 00:06:05.303492 :CBBE0050:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22932 (legacy spawn) 2025-09-11 00:06:05.323674 :CBBE0051: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-09-11 00:06:05.323844 :CBBE005A: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-09-11 00:06:05.324664 :CBBE005B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:07.515971 :CBBE0139:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:07.507 2025-09-11 00:06:07.515974 :CBBE013A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22436 pso_num=29 pso_serial#=41 2025-09-11 00:06:07.516125 :CBBE013B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22436 (legacy spawn) 2025-09-11 00:06:07.523034 :CBBE013C: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-09-11 00:06:07.523230 :CBBE0145: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-09-11 00:06:07.524193 :CBBE0146:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:08.598356 :CBBE0160:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:08.585 2025-09-11 00:06:08.598358 :CBBE0161:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21244 pso_num=29 pso_serial#=42 2025-09-11 00:06:08.598491 :CBBE0162:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21244 (legacy spawn) 2025-09-11 00:06:08.605032 :CBBE0163: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-09-11 00:06:08.605215 :CBBE0168: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-09-11 00:06:08.606015 :CBBE016D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 00:06:08.614679 :CBBE0170:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 00:06:08.601 2025-09-11 00:06:08.614681 :CBBE0171:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23640 pso_num=29 pso_serial#=43 2025-09-11 00:06:08.614822 :CBBE0172:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23640 (legacy spawn) 2025-09-11 00:06:08.621436 :CBBE0173: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-09-11 00:06:08.621610 :CBBE017C: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-09-11 00:06:08.622436 :CBBE017D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:01.587774 :CBC22D4A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:01.573 2025-09-11 01:00:01.587776 :CBC22D4B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24280 pso_num=29 pso_serial#=44 2025-09-11 01:00:01.587932 :CBC22D4C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24280 (legacy spawn) 2025-09-11 01:00:01.594796 :CBC22D4D: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-09-11 01:00:01.594968 :CBC22D52: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-09-11 01:00:01.595840 :CBC22D57:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:01.605370 :CBC22D5A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:01.604 2025-09-11 01:00:01.605372 :CBC22D5B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16340 pso_num=29 pso_serial#=45 2025-09-11 01:00:01.605521 :CBC22D5C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16340 (legacy spawn) 2025-09-11 01:00:01.612389 :CBC22D5D: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-09-11 01:00:01.612559 :CBC22D66: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-09-11 01:00:01.613480 :CBC22D67:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:01.815685 :CBC22D87:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:01.807 2025-09-11 01:00:01.815688 :CBC22D88:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21996 pso_num=29 pso_serial#=46 2025-09-11 01:00:01.815828 :CBC22D89:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21996 (legacy spawn) 2025-09-11 01:00:01.822327 :CBC22D8A: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-09-11 01:00:01.822531 :CBC22D93: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-09-11 01:00:01.823427 :CBC22D94:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:04.811181 :CBC22E77:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:04.797 2025-09-11 01:00:04.811184 :CBC22E78:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24700 pso_num=29 pso_serial#=47 2025-09-11 01:00:04.811322 :CBC22E79:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24700 (legacy spawn) 2025-09-11 01:00:04.817825 :CBC22E7A: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-09-11 01:00:04.817994 :CBC22E7F: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-09-11 01:00:04.818792 :CBC22E84:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 01:00:04.827411 :CBC22E87:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 01:00:04.813 2025-09-11 01:00:04.827413 :CBC22E88:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24676 pso_num=29 pso_serial#=48 2025-09-11 01:00:04.827555 :CBC22E89:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=24676 (legacy spawn) 2025-09-11 01:00:04.834016 :CBC22E8A: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-09-11 01:00:04.834179 :CBC22E93: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-09-11 01:00:04.834983 :CBC22E94:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 08:22:54.908733 :CBE4D4C9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 08:22:54.908 2025-09-11 08:22:54.908735 :CBE4D4CA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=24184 pso_num=29 pso_serial#=49 2025-09-11 08:22:54.911471 :CBE4D4CB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=24184 osp_idx=27 osp_ver=17388 osp_pg=0 (spawn #580612) 2025-09-11 08:22:54.911472 :CBE4D4CC:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 08:22:54.911473 :CBE4D4CD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=228 time=1964626190 2025-09-11 08:22:54.911473 :CBE4D4CE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 08:22:54.911474 :CBE4D4CF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 08:22:54.911474 :CBE4D4D0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 08:22:54.911474 :CBE4D4D1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 08:22:54.911475 :CBE4D4D2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 08:22:54.916698 :CBE4D4D3: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-09-11 08:22:54.916756 :CBE4D4D9: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-09-11 08:22:54.917203 :CBE4D4DA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 08:22:57.107361 :CBE4D5F7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 08:22:57.097 2025-09-11 08:22:57.107363 :CBE4D5F8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25032 pso_num=29 pso_serial#=50 2025-09-11 08:22:57.107497 :CBE4D5F9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=25032 osp_idx=27 osp_ver=17389 osp_pg=0 (spawn #580614) 2025-09-11 08:22:57.107498 :CBE4D5FA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 08:22:57.107499 :CBE4D5FB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=229 time=1964628408 2025-09-11 08:22:57.107499 :CBE4D5FC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 08:22:57.107500 :CBE4D5FD:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 08:22:57.107500 :CBE4D5FE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 08:22:57.107500 :CBE4D5FF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 08:22:57.107501 :CBE4D600:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 08:22:57.108023 :CBE4D601: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-09-11 08:22:57.108068 :CBE4D607: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-09-11 08:22:57.108467 :CBE4D608:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 16:00:19.588228 :CC086CAE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 16:00:19.582 2025-09-11 16:00:19.588230 :CC086CAF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=25260 pso_num=29 pso_serial#=51 2025-09-11 16:00:19.588356 :CC086CB0:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=25260 osp_idx=27 osp_ver=17390 osp_pg=0 (spawn #581670) 2025-09-11 16:00:19.588357 :CC086CB1:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 16:00:19.588357 :CC086CB2:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=230 time=1992070877 2025-09-11 16:00:19.588358 :CC086CB3:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 16:00:19.588358 :CC086CB4:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 16:00:19.588358 :CC086CB5:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 16:00:19.588359 :CC086CB6:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 16:00:19.588359 :CC086CB7:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 16:00:19.588854 :CC086CB8: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-09-11 16:00:19.588902 :CC086CBE: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-09-11 16:00:19.596508 :CC086CCC: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-09-11 16:00:19.597569 :CC086CD4: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-09-11 16:00:19.598050 :CC086CDC: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-09-11 16:00:19.598486 :CC086CE4: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-09-11 16:00:19.598971 :CC086CEC: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-09-11 16:00:19.601387 :CC086CF4: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-09-11 16:00:19.602462 :CC086CFC: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-09-11 16:00:19.653395 :CC086D04: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-09-11 16:00:19.654293 :CC086D0C: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-09-11 16:00:19.654533 :CC086D14: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-09-11 16:00:19.654751 :CC086D1C: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-09-11 16:00:19.656597 :CC086D24:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-09-11 22:00:00.424910 :CC24457A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 09-11 22:00:00.413 2025-09-11 22:00:00.424911 :CC24457B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23240 pso_num=29 pso_serial#=52 2025-09-11 22:00:00.425051 :CC244582:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23240 osp_idx=27 osp_ver=17391 osp_pg=0 (spawn #582502) 2025-09-11 22:00:00.425051 :CC244583:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-11 22:00:00.425052 :CC244584:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=231 time=2013651721 2025-09-11 22:00:00.425052 :CC244585:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-09-11 22:00:00.425053 :CC244586:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-09-11 22:00:00.425053 :CC244587:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-09-11 22:00:00.425054 :CC244588:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-11 22:00:00.425054 :CC244589:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-11 22:00:00.425544 :CC24458A: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-09-11 22:00:00.430619 :CC2445A1: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-09-11 22:00:00.430655 :CC2445A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-09-11 22:00:00.448284 :CC24478A: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-09-11 22:00:00.448593 :CC244792: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-09-11 22:00:00.448768 :CC24479A: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-09-11 22:00:00.448933 :CC2447A2: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-09-11 22:00:00.449093 :CC2447AA: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-09-11 22:00:00.449245 :CC2447B2: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-09-11 22:00:00.449520 :CC2447BA: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-09-11 22:00:00.449739 :CC2447C2: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-09-11 22:00:00.449929 :CC2447CA: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-09-11 22:00:00.450089 :CC2447D2: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-09-11 22:00:00.450257 :CC2447DA: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-09-11 22:00:00.450654 :CC2447E2: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-09-11 22:00:00.450840 :CC2447EA: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-09-11 22:00:00.451002 :CC2447F2: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-09-11 22:00:00.451267 :CC2447FA: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-09-11 22:00:00.451514 :CC244802: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-09-11 22:00:00.451762 :CC24480A: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-09-11 22:00:00.452403 :CC244812: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-09-11 22:00:00.478850 :CC24481A: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-09-11 22:00:01.522177*:CC244A05:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1757599201 eid=22269343 ctx=0x0000000777968158 cwh=0x000000002B460AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-09-11 22:00:01.522177*:CC244A06:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x000000005F25C4B8 pubTabIdxCnt=0 2025-09-11 22:00:01.522177*:CC244A07:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-09-11 22:00:01.522177*:CC244A08:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-09-11 22:00:01.522177*:CC244A09:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003261AAF8 estart=1757599200 eid=16777579 ctx=0x000000077B54EAC8 cwh=0x000000002B460608 cpu=859375 buffg=49359 interb=196608 r=24 rb=196608 w=0 wb=0 2025-09-11 22:00:01.522177*:CC244A0A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003261AAF8 curCtx=0x000000005F071240 pubTabIdxCnt=0 2025-09-11 22:00:01.522177*:CC244A0B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003261AAF8 2025-09-11 22:00:01.522177*:CC244A0C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003261AAF8 2025-09-11 22:00:01.522177*:CC244A0D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x000000005F25C4B8 xsc->flg4=65792 flags=1 2025-09-11 22:00:01.522177*:CC244A0E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-09-11 22:00:02.115936*:CC244B36:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003261AAF8 curCtx=0x000000005F071240 xsc->flg4=65792 flags=2 2025-09-11 22:00:02.115936*:CC244B37:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003261AAF8 2025-09-11 22:00:02.128856 :CC244B38: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-09-11 22:00:02.151724 :CC244B60: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-09-11 22:00:02.154007 :CC244B68:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-09-11 22:00:02.154254 :CC244B69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-09-11 22:00:02.155288 :CC244B6A: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-09-11 22:00:02.155425 :CC244B6D: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-09-11 22:00:02.156497 :CC244B7F: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-09-11 22:00:02.158287 :CC244B9F: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-09-11 22:00:02.158627 :CC244BAA: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-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-11 22:00:02.225313*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-11 22:00:02.310245 :CC244C4F: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-09-11 22:00:04.263370 :CC244DA4: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-09-11 22:00:04.263385 :CC244DA5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0001.012.0000ca13 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 23240, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de