Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250604220005\orcl_j003_13636_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250604220005\orcl_j003_13636_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:36946M/63366M, Ph+PgF:43664M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 13636, image: ORACLE.EXE (J003) *** 2025-06-04 22:00:05.597 *** SESSION ID:(233.24277) 2025-06-04 22:00:05.597 *** 2025-06-04 22:00:05.597 Process diagnostic dump for ORACLE.EXE (J003), OS id=13636, pid: 29, proc_ser: 122, sid: 233, sess_ser: 24277 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13636 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12541, hash value=243666481 Current Wait Stack: Not in wait; last wait ended 2.716864 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.716889 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000156 sec, exc=0.000156 sec, total=0.000156 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.063990 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.041926 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000142 sec, exc=0.000142 sec, total=0.000142 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.713005 sec of elapsed time 3: 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.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.026771 sec of elapsed time 4: 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.013039 sec of elapsed time 5: 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.000120 sec, exc=0.000120 sec, total=0.000120 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052529 sec of elapsed time 6: 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.004257 sec, exc=0.004257 sec, total=0.004257 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007907 sec of elapsed time 7: 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.008488 sec, exc=0.008488 sec, total=0.008488 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261542 sec of elapsed time 8: 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.000146 sec, exc=0.000146 sec, total=0.000146 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000323 sec of elapsed time 9: 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.004205 sec, exc=0.004205 sec, total=0.004205 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007812 sec of elapsed time Sampled Session History of session 233 serial 24277 --------------------------------------------------- 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:01 - 22:00:05] not in wait at each sample [1 sample, 22:00:00] idle wait at each sample [session created at: 22:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-04 22:00:05.597 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 13636, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-04 01:44:56.606756 :9FFE5D41:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 01:44:56.606756 :9FFE5D42:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 01:44:56.606757 :9FFE5D43:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 01:44:56.607267 :9FFE5D44: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-06-04 01:44:56.607313 :9FFE5D4A: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-06-04 01:44:56.611456 :9FFE5D72:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-04 01:44:56.611500 :9FFE5D73:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-04 01:44:56.611754 :9FFE5D74:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-04 01:44:56.611770 :9FFE5D75:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-04 01:44:56.611979 :9FFE5D76:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-04 01:44:56.611993 :9FFE5D77:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-04 01:44:56.612194 :9FFE5D78:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-04 01:44:56.612207 :9FFE5D79:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-04 01:44:56.612417 :9FFE5D7A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-04 01:44:56.612431 :9FFE5D7B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-04 01:44:56.612632 :9FFE5D7C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-06-04 01:44:56.612645 :9FFE5D7D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-04 01:44:56.615075 :9FFE5D7E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 03:00:05.841159 :A004437D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 03:00:05.839 2025-06-04 03:00:05.841159 :A004437E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14980 pso_num=29 pso_serial#=114 2025-06-04 03:00:05.841283 :A004437F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14980 osp_idx=27 osp_ver=12098 osp_pg=0 (spawn #249853) 2025-06-04 03:00:05.841284 :A0044380:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 03:00:05.841285 :A0044381:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=254 time=1981764469 2025-06-04 03:00:05.841285 :A0044382:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 03:00:05.841285 :A0044383:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 03:00:05.841286 :A0044384:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 03:00:05.841286 :A0044385:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 03:00:05.841287 :A0044386:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 03:00:05.841796 :A0044387: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-06-04 03:00:05.841843 :A004438D: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-06-04 03:00:05.850281 :A00443B2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 04:00:13.272474 :A008FCD0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 04:00:13.257 2025-06-04 04:00:13.272475 :A008FCD1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15876 pso_num=29 pso_serial#=115 2025-06-04 04:00:13.272630 :A008FCD2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15876 osp_idx=27 osp_ver=12099 osp_pg=0 (spawn #249992) 2025-06-04 04:00:13.272631 :A008FCD3:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 04:00:13.272631 :A008FCD4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=255 time=1985371907 2025-06-04 04:00:13.272632 :A008FCD5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 04:00:13.272632 :A008FCD6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 04:00:13.272632 :A008FCD7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 04:00:13.272633 :A008FCD8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 04:00:13.272633 :A008FCD9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 04:00:13.273162 :A008FCDA: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-06-04 04:00:13.273201 :A008FCE0: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-06-04 04:00:13.281633 :A008FD06:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 05:00:20.246335 :A00DB4D1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 05:00:20.244 2025-06-04 05:00:20.246337 :A00DB4D2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15856 pso_num=29 pso_serial#=116 2025-06-04 05:00:20.246478 :A00DB4D3:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15856 osp_idx=27 osp_ver=12100 osp_pg=0 (spawn #250130) 2025-06-04 05:00:20.246479 :A00DB4D4:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 05:00:20.246479 :A00DB4D5:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=0 time=1988978875 2025-06-04 05:00:20.246480 :A00DB4D6:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 05:00:20.246481 :A00DB4D7:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 05:00:20.246481 :A00DB4D8:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 05:00:20.246482 :A00DB4D9:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 05:00:20.246482 :A00DB4DA:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 05:00:20.246995 :A00DB4DB: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-06-04 05:00:20.247039 :A00DB4E1: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-06-04 05:00:20.255347 :A00DB4F6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 06:00:27.284258 :A0126F44:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 06:00:27.281 2025-06-04 06:00:27.284259 :A0126F45:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2472 pso_num=29 pso_serial#=117 2025-06-04 06:00:27.284394 :A0126F46:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=2472 osp_idx=27 osp_ver=12101 osp_pg=0 (spawn #250269) 2025-06-04 06:00:27.284395 :A0126F47:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 06:00:27.284395 :A0126F48:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=1 time=1992585922 2025-06-04 06:00:27.284396 :A0126F49:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 06:00:27.284396 :A0126F4A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 06:00:27.284396 :A0126F4B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 06:00:27.284397 :A0126F4C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 06:00:27.284397 :A0126F4D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 06:00:27.284901 :A0126F4E: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-06-04 06:00:27.284950 :A0126F54: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-06-04 06:00:27.293222 :A0126F69:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 07:00:34.813379 :A0172693:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 07:00:34.811 2025-06-04 07:00:34.813380 :A0172694:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15240 pso_num=29 pso_serial#=118 2025-06-04 07:00:34.813521 :A0172695:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15240 osp_idx=27 osp_ver=12102 osp_pg=0 (spawn #250408) 2025-06-04 07:00:34.813522 :A0172696:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 07:00:34.813523 :A0172697:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=2 time=1996193454 2025-06-04 07:00:34.813523 :A0172698:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 07:00:34.813523 :A0172699:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 07:00:34.813524 :A017269A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 07:00:34.813524 :A017269B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 07:00:34.813524 :A017269C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 07:00:34.814052 :A017269D: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-06-04 07:00:34.814096 :A01726A3: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-06-04 07:00:34.822612 :A01726B8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 08:00:42.100405 :A01BDC12:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 08:00:42.100 2025-06-04 08:00:42.100407 :A01BDC13:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14648 pso_num=29 pso_serial#=119 2025-06-04 08:00:42.100544 :A01BDC14:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14648 osp_idx=27 osp_ver=12103 osp_pg=0 (spawn #250547) 2025-06-04 08:00:42.100545 :A01BDC15:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 08:00:42.100546 :A01BDC16:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=3 time=1999800750 2025-06-04 08:00:42.100546 :A01BDC17:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 08:00:42.100547 :A01BDC18:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 08:00:42.100547 :A01BDC19:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 08:00:42.100547 :A01BDC1A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 08:00:42.100548 :A01BDC1B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 08:00:42.101068 :A01BDC1C: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-06-04 08:00:42.101108 :A01BDC22: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-06-04 08:00:42.109419 :A01BDC37:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 09:00:49.075433 :A020F344:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 09:00:49.071 2025-06-04 09:00:49.075434 :A020F345:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11852 pso_num=29 pso_serial#=120 2025-06-04 09:00:49.075588 :A020F346:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=11852 osp_idx=27 osp_ver=12104 osp_pg=0 (spawn #250690) 2025-06-04 09:00:49.075589 :A020F347:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 09:00:49.075589 :A020F348:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=4 time=2003407704 2025-06-04 09:00:49.075590 :A020F349:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 09:00:49.075590 :A020F34A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 09:00:49.075591 :A020F34B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 09:00:49.075591 :A020F34C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 09:00:49.075591 :A020F34D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 09:00:49.076137 :A020F34E: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-06-04 09:00:49.076186 :A020F354: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-06-04 09:00:49.084916 :A020F379:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 10:00:56.228316 :A0259A6B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 10:00:56.225 2025-06-04 10:00:56.228317 :A0259A6C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16268 pso_num=29 pso_serial#=121 2025-06-04 10:00:56.228457 :A0259A6D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16268 osp_idx=27 osp_ver=12105 osp_pg=0 (spawn #250829) 2025-06-04 10:00:56.228458 :A0259A6E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 10:00:56.228459 :A0259A6F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=5 time=2007014860 2025-06-04 10:00:56.228460 :A0259A70:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 10:00:56.228460 :A0259A71:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 10:00:56.228460 :A0259A72:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 10:00:56.228461 :A0259A73:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 10:00:56.228461 :A0259A74:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 10:00:56.228962 :A0259A75: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-06-04 10:00:56.229018 :A0259A7B: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-06-04 10:00:56.237018 :A0259AA0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 22:00:01.079222 :A05CB7FD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 22:00:01.067 2025-06-04 22:00:01.079225 :A05CB7FE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13636 pso_num=29 pso_serial#=122 2025-06-04 22:00:01.079379 :A05CB7FF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13636 osp_idx=27 osp_ver=12106 osp_pg=0 (spawn #252488) 2025-06-04 22:00:01.079380 :A05CB800:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-04 22:00:01.079380 :A05CB801:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=6 time=2050159719 2025-06-04 22:00:01.079380 :A05CB802:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-04 22:00:01.079381 :A05CB803:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-04 22:00:01.079382 :A05CB804:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-04 22:00:01.079382 :A05CB805:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-04 22:00:01.079383 :A05CB806:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-04 22:00:01.079962 :A05CB810: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-06-04 22:00:01.084890 :A05CB825: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-06-04 22:00:01.084933 :A05CB828:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-04 22:00:01.101998 :A05CBA13: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-06-04 22:00:01.102313 :A05CBA1B: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-06-04 22:00:01.102479 :A05CBA23: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-06-04 22:00:01.102659 :A05CBA2B: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-06-04 22:00:01.102892 :A05CBA33: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-06-04 22:00:01.103051 :A05CBA3B: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-06-04 22:00:01.103225 :A05CBA43: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-06-04 22:00:01.103407 :A05CBA4B: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-06-04 22:00:01.103582 :A05CBA53: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-06-04 22:00:01.103746 :A05CBA5B: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-06-04 22:00:01.103905 :A05CBA63: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-06-04 22:00:01.104274 :A05CBA6B: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-06-04 22:00:01.104454 :A05CBA73: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-06-04 22:00:01.104681 :A05CBA7B: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-06-04 22:00:01.104944 :A05CBA83: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-06-04 22:00:01.105194 :A05CBA8B: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-06-04 22:00:01.105436 :A05CBA93: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-06-04 22:00:01.106040 :A05CBA9B: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-06-04 22:00:01.132455 :A05CBAA3: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-06-04 22:00:02.155174*:A05CBC15:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC93AD0 estart=1749045601 eid=19079677 ctx=0x0000000777968158 cwh=0x0000000029A90AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-04 22:00:02.155174*:A05CBC16:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC93AD0 curCtx=0x000000004285C4B8 pubTabIdxCnt=0 2025-06-04 22:00:02.155174*:A05CBC17:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC93AD0 2025-06-04 22:00:02.155174*:A05CBC18:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC93AD0 2025-06-04 22:00:02.155174*:A05CBC19:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DCDAAF8 estart=1749045601 eid=16777368 ctx=0x000000077B54EAC8 cwh=0x0000000029A90608 cpu=828125 buffg=47480 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-04 22:00:02.155174*:A05CBC1A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DCDAAF8 curCtx=0x0000000042671240 pubTabIdxCnt=0 2025-06-04 22:00:02.155174*:A05CBC1B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DCDAAF8 2025-06-04 22:00:02.155174*:A05CBC1C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DCDAAF8 2025-06-04 22:00:02.155174*:A05CBC1D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC93AD0 curCtx=0x000000004285C4B8 xsc->flg4=65792 flags=1 2025-06-04 22:00:02.155174*:A05CBC1E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC93AD0 2025-06-04 22:00:02.790293*:A05CBD78:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DCDAAF8 curCtx=0x0000000042671240 xsc->flg4=65792 flags=2 2025-06-04 22:00:02.790293*:A05CBD79:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DCDAAF8 2025-06-04 22:00:02.804987 :A05CBD7F: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-06-04 22:00:02.825435 :A05CBDB0: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-06-04 22:00:02.827427 :A05CBDB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-04 22:00:02.827632 :A05CBDB9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-04 22:00:02.828507 :A05CBDBA: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-06-04 22:00:02.828601 :A05CBDBD: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-06-04 22:00:02.829490 :A05CBDCF: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-06-04 22:00:02.831024 :A05CBDD7: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-06-04 22:00:02.831322 :A05CBDDF: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-06-04 22:00:02.884043*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-04 22:00:02.884043*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-04 22:00:02.884043*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-04 22:00:02.930925*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-04 22:00:02.930925*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-04 22:00:02.930925*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-04 22:00:02.930925*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-04 22:00:02.991672 :A05CBE69: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-06-04 22:00:04.903678 :A05CC013: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-06-04 22:00:04.903691 :A05CC014:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x000a.014.0016c6d1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 13636, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de