Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250522220955\orcl_m002_13856_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250522220955\orcl_m002_13856_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:37351M/63366M, Ph+PgF:44419M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 13856, image: ORACLE.EXE (M002) *** 2025-05-22 22:09:55.608 *** SESSION ID:(233.22915) 2025-05-22 22:09:55.608 *** 2025-05-22 22:09:55.608 Process diagnostic dump for ORACLE.EXE (M002), OS id=13856, pid: 29, proc_ser: 209, sid: 233, sess_ser: 22915 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x5b3a, blocks=0x1 wait_id=1638 seq_num=1639 snap_id=1 wait times: snap=0.000025 sec, exc=0.000025 sec, total=0.000025 sec wait times: max=infinite, heur=0.000025 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000351 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x5b0a, blocks=0x1 wait_id=1637 seq_num=1638 snap_id=1 wait times: snap=0.003243 sec, exc=0.003243 sec, total=0.003243 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000270 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x574a, blocks=0x1 wait_id=1636 seq_num=1637 snap_id=1 wait times: snap=0.000871 sec, exc=0.000871 sec, total=0.000871 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000345 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x5712, blocks=0x1 wait_id=1635 seq_num=1636 snap_id=1 wait times: snap=0.002838 sec, exc=0.002838 sec, total=0.002838 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000363 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x565a, blocks=0x1 wait_id=1634 seq_num=1635 snap_id=1 wait times: snap=0.003322 sec, exc=0.003322 sec, total=0.003322 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000349 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x562a, blocks=0x1 wait_id=1633 seq_num=1634 snap_id=1 wait times: snap=0.002899 sec, exc=0.002899 sec, total=0.002899 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000395 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x52da, blocks=0x1 wait_id=1632 seq_num=1633 snap_id=1 wait times: snap=0.005281 sec, exc=0.005281 sec, total=0.005281 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000231 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x52ba, blocks=0x1 wait_id=1631 seq_num=1632 snap_id=1 wait times: snap=0.001280 sec, exc=0.001280 sec, total=0.001280 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000158 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x4a82, blocks=0x1 wait_id=1630 seq_num=1631 snap_id=1 wait times: snap=0.000115 sec, exc=0.000115 sec, total=0.000115 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000193 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x4d52, blocks=0x1 wait_id=1629 seq_num=1630 snap_id=1 wait times: snap=0.001327 sec, exc=0.001327 sec, total=0.001327 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000144 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x4d42, blocks=0x1 wait_id=1628 seq_num=1629 snap_id=1 wait times: snap=0.000057 sec, exc=0.000057 sec, total=0.000057 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000173 sec of elapsed time Sampled Session History of session 233 serial 22915 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:09:55] idle wait at each sample [session created at: 22:09:54] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-22 22:09:55.608 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 13856, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-22 08:03:38.134074 :9A6A6E6A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12824 pso_num=29 pso_serial#=201 2025-05-22 08:03:38.134209 :9A6A6E6C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12824 osp_idx=27 osp_ver=11420 osp_pg=0 (spawn #207245) 2025-05-22 08:03:38.134210 :9A6A6E6D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 08:03:38.134210 :9A6A6E6E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=225 time=876793016 2025-05-22 08:03:38.134211 :9A6A6E6F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 08:03:38.134211 :9A6A6E70:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 08:03:38.134211 :9A6A6E71:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 08:03:38.134211 :9A6A6E72:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 08:03:38.134211 :9A6A6E73:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 08:03:38.134749 :9A6A6E74: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-05-22 08:03:38.134785 :9A6A6E7B: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-05-22 08:03:38.144389 :9A6A6EA1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 09:03:46.887145 :9A6F0E81:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 09:03:46.883 2025-05-22 09:03:46.887146 :9A6F0E82:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8776 pso_num=29 pso_serial#=202 2025-05-22 09:03:46.887283 :9A6F0E83:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=8776 osp_idx=27 osp_ver=11421 osp_pg=0 (spawn #207384) 2025-05-22 09:03:46.887284 :9A6F0E84:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 09:03:46.887284 :9A6F0E85:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=226 time=880401766 2025-05-22 09:03:46.887285 :9A6F0E86:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 09:03:46.887285 :9A6F0E87:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 09:03:46.887286 :9A6F0E88:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 09:03:46.887286 :9A6F0E89:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 09:03:46.887286 :9A6F0E8A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 09:03:46.887796 :9A6F0E8B: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-05-22 09:03:46.887844 :9A6F0E91: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-05-22 09:03:46.897036 :9A6F0EA9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 10:03:55.574263 :9A73A165:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 10:03:55.571 2025-05-22 10:03:55.574264 :9A73A166:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12152 pso_num=29 pso_serial#=203 2025-05-22 10:03:55.574397 :9A73A167:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12152 osp_idx=27 osp_ver=11422 osp_pg=0 (spawn #207522) 2025-05-22 10:03:55.574398 :9A73A168:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 10:03:55.574398 :9A73A169:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=227 time=884010454 2025-05-22 10:03:55.574398 :9A73A16A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 10:03:55.574399 :9A73A16B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 10:03:55.574399 :9A73A16C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 10:03:55.574399 :9A73A16D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 10:03:55.574400 :9A73A16E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 10:03:55.574905 :9A73A16F: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-05-22 10:03:55.574957 :9A73A175: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-05-22 10:03:55.584023 :9A73A18D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 11:04:03.603240 :9A783F21:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 11:04:03.603 2025-05-22 11:04:03.603242 :9A783F22:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9532 pso_num=29 pso_serial#=204 2025-05-22 11:04:03.603387 :9A783F23:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=9532 osp_idx=27 osp_ver=11423 osp_pg=0 (spawn #207661) 2025-05-22 11:04:03.603388 :9A783F24:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 11:04:03.603388 :9A783F25:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=228 time=887618485 2025-05-22 11:04:03.603388 :9A783F26:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 11:04:03.603389 :9A783F27:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 11:04:03.603389 :9A783F28:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 11:04:03.603390 :9A783F29:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 11:04:03.603390 :9A783F2A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 11:04:03.603935 :9A783F2B: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-05-22 11:04:03.603980 :9A783F31: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-05-22 11:04:03.613114 :9A783F49:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 22:00:02.048780 :9AAA3E62:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 22:00:02.038 2025-05-22 22:00:02.048781 :9AAA3E63:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13592 pso_num=29 pso_serial#=205 2025-05-22 22:00:02.048917 :9AAA3E66:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13592 osp_idx=27 osp_ver=11424 osp_pg=0 (spawn #209174) 2025-05-22 22:00:02.048918 :9AAA3E67:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:00:02.048919 :9AAA3E68:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=229 time=926976938 2025-05-22 22:00:02.048919 :9AAA3E69:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:00:02.048920 :9AAA3E6A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:00:02.048920 :9AAA3E6B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:00:02.048921 :9AAA3E6C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:00:02.048921 :9AAA3E6D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:00:02.049480 :9AAA3E75: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-05-22 22:00:02.054449 :9AAA3FD1: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-05-22 22:00:02.054487 :9AAA3FD4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-05-22 22:00:02.060105 :9AAA4001: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-05-22 22:00:02.268977 :9AAA418A: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-05-22 22:00:02.445278 :9AAA41F2: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-05-22 22:00:02.581546 :9AAA4212: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-05-22 22:00:03.704475 :9AAA4416: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-05-22 22:00:03.705530 :9AAA441E: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-05-22 22:00:03.709638 :9AAA4426: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-05-22 22:00:03.712782 :9AAA442E: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-05-22 22:00:03.714306 :9AAA4436: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-05-22 22:00:03.715765 :9AAA443E: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-05-22 22:00:03.717266 :9AAA4446: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-05-22 22:00:03.736975 :9AAA444E: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-05-22 22:00:03.737661 :9AAA4456: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-05-22 22:00:03.738174 :9AAA445E: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-05-22 22:00:03.738669 :9AAA4466: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-05-22 22:00:03.741622 :9AAA446E: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-05-22 22:00:03.742579 :9AAA4476:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.743405 :9AAA4477:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.744242 :9AAA4478:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.744773 :9AAA4479:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.744779 :9AAA447A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.745400 :9AAA447B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.745946 :9AAA447C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.746242 :9AAA447D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.747354 :9AAA447E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.784737 :9AAA44BE: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-05-22 22:00:03.784800 :9AAA44C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.785858 :9AAA44C7: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-05-22 22:00:03.786924 :9AAA44CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.787409 :9AAA44D0: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-05-22 22:00:03.809869 :9AAA44D8: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-05-22 22:00:03.809937 :9AAA44DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.810983 :9AAA44E1: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-05-22 22:00:03.824508 :9AAA44E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.825158 :9AAA44EA: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-05-22 22:00:03.848118 :9AAA44F2: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-05-22 22:00:03.848195 :9AAA44F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.849394 :9AAA44FB: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-05-22 22:00:03.850729 :9AAA4503:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.851274 :9AAA4504: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-05-22 22:00:03.870232 :9AAA450C: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-05-22 22:00:03.870287 :9AAA450F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.871260 :9AAA4515: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-05-22 22:00:03.872241 :9AAA451D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.872731 :9AAA451E: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-05-22 22:00:03.894700 :9AAA4526: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-05-22 22:00:03.894759 :9AAA4529:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.895617 :9AAA452F: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-05-22 22:00:03.896459 :9AAA4537:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-05-22 22:00:03.896876 :9AAA4538: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-05-22 22:00:03.901680*:9AAA4541:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000318DCC78 estart=1747922402 eid=16777851 ctx=0x000000077B42EF30 cwh=0x0000000031950158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-22 22:00:03.901680*:9AAA4542:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000318DCC78 curCtx=0x00000000319778E0 pubTabIdxCnt=0 2025-05-22 22:00:03.901680*:9AAA4543:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000318DCC78 2025-05-22 22:00:03.901680*:9AAA4544:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000318DCC78 2025-05-22 22:00:03.901680*:9AAA4545:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031FEBEF0 estart=1747922402 eid=16777851 ctx=0x000000077B447BE8 cwh=0x0000000031FF0250 cpu=15625 buffg=283 interb=0 r=0 rb=0 w=0 wb=0 2025-05-22 22:00:03.901680*:9AAA4547:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031FEBEF0 curCtx=0x0000000031F8AAE0 pubTabIdxCnt=0 2025-05-22 22:00:03.901680*:9AAA4548:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031FEBEF0 2025-05-22 22:00:03.901680*:9AAA4549:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031FEBEF0 2025-05-22 22:00:03.901680*:9AAA454B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000319344B8 estart=1747922402 eid=16777851 ctx=0x000000077B458BE8 cwh=0x0000000031932A50 cpu=15625 buffg=320 interb=0 r=0 rb=0 w=0 wb=0 2025-05-22 22:00:03.901680*:9AAA454D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000319344B8 curCtx=0x0000000032068598 pubTabIdxCnt=0 2025-05-22 22:00:03.901680*:9AAA454E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000319344B8 2025-05-22 22:00:03.901680*:9AAA454F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000319344B8 2025-05-22 22:00:03.901680*:9AAA455C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000318DCC78 curCtx=0x00000000319778E0 xsc->flg4=65792 flags=1 2025-05-22 22:00:03.901680*:9AAA455D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000318DCC78 2025-05-22 22:00:03.901680*:9AAA455E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031FEBEF0 curCtx=0x0000000031F8AAE0 xsc->flg4=65792 flags=1 2025-05-22 22:00:03.901680*:9AAA455F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031FEBEF0 2025-05-22 22:00:03.917314*:9AAA4568:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000319344B8 curCtx=0x0000000032068598 xsc->flg4=65792 flags=2 2025-05-22 22:00:03.917314*:9AAA4569:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000319344B8 2025-05-22 22:00:03.917816 :9AAA456A: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-05-22 22:00:03.917865 :9AAA456D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-05-22 22:00:03.918729 :9AAA4573: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-05-22 22:00:03.919168 :9AAA457B: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-05-22 22:00:03.919512 :9AAA4583: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-05-22 22:00:03.919769 :9AAA458B: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-05-22 22:00:03.919934 :9AAA4593: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-05-22 22:00:03.920082 :9AAA459B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-05-22 22:00:03.923872 :9AAA459C: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-05-22 22:00:03.923985 :9AAA459F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-22 22:00:03.924058 :9AAA45A7: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-05-22 22:00:33.990513 :9AAA528C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 22:00:34.092188 :9AAA529B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 22:00:34.083 2025-05-22 22:00:34.092190 :9AAA529C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12580 pso_num=29 pso_serial#=206 2025-05-22 22:00:34.092326 :9AAA529D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12580 (legacy spawn) 2025-05-22 22:00:34.098916 :9AAA529E: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-05-22 22:00:34.099096 :9AAA52A3: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-05-22 22:00:34.099977 :9AAA52AB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 22:09:53.553824 :9AAB4D5D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 22:09:53.545 2025-05-22 22:09:53.553826 :9AAB4D5E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12772 pso_num=29 pso_serial#=207 2025-05-22 22:09:53.553986 :9AAB4D67:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12772 osp_idx=27 osp_ver=11426 osp_pg=0 (spawn #209204) 2025-05-22 22:09:53.553987 :9AAB4D68:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:09:53.553987 :9AAB4D69:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=230 time=927568454 2025-05-22 22:09:53.553987 :9AAB4D6A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:09:53.553988 :9AAB4D6B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:09:53.553988 :9AAB4D6C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:09:53.553989 :9AAB4D6D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:09:53.553989 :9AAB4D6E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:09:53.554802 :9AAB4D8F: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-05-22 22:09:53.554844 :9AAB4D98: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-05-22 22:09:53.647238 :9AAB5111:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 22:09:53.839878 :9AAB5120:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 22:09:53.827 2025-05-22 22:09:53.839881 :9AAB5121:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13196 pso_num=29 pso_serial#=208 2025-05-22 22:09:53.840063 :9AAB5122:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13196 (legacy spawn) 2025-05-22 22:09:53.866455 :9AAB5123: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-05-22 22:09:53.866629 :9AAB5128: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-05-22 22:09:53.867545 :9AAB512D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-05-22 22:09:54.864438 :9AAB51E7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 05-22 22:09:54.858 2025-05-22 22:09:54.864440 :9AAB51E8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13856 pso_num=29 pso_serial#=209 2025-05-22 22:09:54.864567 :9AAB51E9:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13856 osp_idx=27 osp_ver=11428 osp_pg=0 (spawn #209206) 2025-05-22 22:09:54.864568 :9AAB51EA:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 22:09:54.864568 :9AAB51EB:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=231 time=927569766 2025-05-22 22:09:54.864568 :9AAB51EC:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 22:09:54.864569 :9AAB51ED:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 22:09:54.864569 :9AAB51EE:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 22:09:54.864569 :9AAB51EF:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 22:09:54.864570 :9AAB51F0:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 22:09:54.865063 :9AAB51F1: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-05-22 22:09:54.865104 :9AAB51F7: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 13856, M002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de