Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250909220008\orcl_j002_19952_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:34874M/63366M, Ph+PgF:39587M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 19952, image: ORACLE.EXE (J002) *** 2025-09-09 22:00:08.663 *** SESSION ID:(225.26941) 2025-09-09 22:00:08.663 *** 2025-09-09 22:00:08.663 Process diagnostic dump for ORACLE.EXE (J002), OS id=19952, pid: 28, proc_ser: 69, sid: 225, sess_ser: 26941 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19952 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13056, hash value=1293477986 Current Wait Stack: Not in wait; last wait ended 0.896897 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.896921 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000238 sec, exc=0.000238 sec, total=0.000238 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2a5b, block#=0x0, blocks=0x0 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.009660 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2a5b, block#=0x0, blocks=0x0 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000019 sec, exc=0.000019 sec, total=0.000019 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.542117 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x3000, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000322 sec, exc=0.000322 sec, total=0.000322 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000030 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x2800, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000304 sec, exc=0.000304 sec, total=0.000304 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.003506 sec of elapsed time 5: waited for 'log file sequential read' log#=0x2a5b, block#=0x800, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000199 sec, exc=0.000199 sec, total=0.000199 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002590 sec of elapsed time 6: waited for 'log file sequential read' log#=0x2a5b, block#=0x0, blocks=0x0 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000144 sec of elapsed time 7: waited for 'log file sequential read' log#=0x2a5b, block#=0x2, blocks=0x7fe wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000198 sec, exc=0.000198 sec, total=0.000198 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000165 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time 9: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x0, filetype=0x3 wait_id=133 seq_num=134 snap_id=1 wait times: snap=0.000256 sec, exc=0.000256 sec, total=0.000256 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000147 sec of elapsed time Sampled Session History of session 225 serial 26941 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:00:02 - 22:00:07] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-09 22:00:08.663 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 19952, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-09 16:00:25.911776 :CB270830:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 16:00:25.911954 :CB270835:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 16:00:25.912739 :CB27083A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 16:00:25.921501 :CB27083D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 16:00:25.907 2025-09-09 16:00:25.921502 :CB27083E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17500 pso_num=28 pso_serial#=60 2025-09-09 16:00:25.921637 :CB27083F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17500 (legacy spawn) 2025-09-09 16:00:25.928097 :CB270840:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 16:00:25.928266 :CB270849:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 16:00:25.929056 :CB27084A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 16:02:33.862371 :CB2733BF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 16:02:33.850 2025-09-09 16:02:33.862372 :CB2733C0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21892 pso_num=28 pso_serial#=61 2025-09-09 16:02:33.862522 :CB2733C1:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21892 osp_idx=26 osp_ver=308149 osp_pg=0 (spawn #575005) 2025-09-09 16:02:33.862523 :CB2733C2:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 16:02:33.862524 :CB2733C3:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=80 time=1819405158 2025-09-09 16:02:33.862524 :CB2733C4:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 16:02:33.862524 :CB2733C5:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 16:02:33.862525 :CB2733C6:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 16:02:33.862525 :CB2733C7:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 16:02:33.862525 :CB2733C8:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 16:02:33.863050 :CB2733C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 16:02:33.863094 :CB2733CF:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 16:02:33.871555 :CB2733E4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 16:12:35.112251 :CB2803A8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 16:12:35.099 2025-09-09 16:12:35.112253 :CB2803A9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20852 pso_num=28 pso_serial#=62 2025-09-09 16:12:35.112400 :CB2803AA:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20852 osp_idx=26 osp_ver=308150 osp_pg=0 (spawn #575028) 2025-09-09 16:12:35.112400 :CB2803AB:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 16:12:35.112401 :CB2803AC:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=81 time=1820006408 2025-09-09 16:12:35.112401 :CB2803AD:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 16:12:35.112402 :CB2803AE:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 16:12:35.112402 :CB2803AF:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 16:12:35.112402 :CB2803B0:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 16:12:35.112403 :CB2803B1:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 16:12:35.112911 :CB2803B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 16:12:35.112956 :CB2803B8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 16:12:35.117064 :CB2803E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-09 16:12:35.117104 :CB2803E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-09 16:12:35.117356 :CB2803E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-09 16:12:35.117371 :CB2803E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-09 16:12:35.117588 :CB2803E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-09 16:12:35.117601 :CB2803E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-09 16:12:35.117808 :CB2803E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-09 16:12:35.117821 :CB2803E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-09 16:12:35.118026 :CB2803E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-09 16:12:35.118039 :CB2803E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-09 16:12:35.118246 :CB2803EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-09 16:12:35.118259 :CB2803EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-09 16:12:35.120837 :CB2803EC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 19:42:38.545921 :CB3844C9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 19:42:38.539 2025-09-09 19:42:38.545924 :CB3844CA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23692 pso_num=28 pso_serial#=63 2025-09-09 19:42:38.546070 :CB3844CB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23692 (legacy spawn) 2025-09-09 19:42:38.552923 :CB3844CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 19:42:38.553089 :CB3844D1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 19:42:38.554127 :CB3844D6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 19:42:38.562675 :CB3844D9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 19:42:38.554 2025-09-09 19:42:38.562678 :CB3844DA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19536 pso_num=28 pso_serial#=64 2025-09-09 19:42:38.562817 :CB3844DB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19536 (legacy spawn) 2025-09-09 19:42:38.569263 :CB3844DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 19:42:38.569501 :CB3844E4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 19:42:38.570393 :CB3844E6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 19:42:41.703221 :CB3845D6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 19:42:41.689 2025-09-09 19:42:41.703223 :CB3845D7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23868 pso_num=28 pso_serial#=65 2025-09-09 19:42:41.703360 :CB3845D8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23868 (legacy spawn) 2025-09-09 19:42:41.709849 :CB3845D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 19:42:41.710016 :CB3845DE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 19:42:41.710806 :CB3845E3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 19:42:41.719550 :CB3845E6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 19:42:41.705 2025-09-09 19:42:41.719552 :CB3845E7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7280 pso_num=28 pso_serial#=66 2025-09-09 19:42:41.719689 :CB3845E8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7280 (legacy spawn) 2025-09-09 19:42:41.739474 :CB3845E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 19:42:41.739641 :CB3845F1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 19:42:41.740456 :CB3845F3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 19:42:52.706091 :CB384974:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 19:42:52.698 2025-09-09 19:42:52.706094 :CB384975:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21748 pso_num=28 pso_serial#=67 2025-09-09 19:42:52.706227 :CB384976:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21748 (legacy spawn) 2025-09-09 19:42:52.712723 :CB384977:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 19:42:52.712896 :CB38497C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 19:42:52.713732 :CB384981:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 22:00:00.030555 :CB431018:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 22:00:00.021 2025-09-09 22:00:00.030557 :CB431019:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16724 pso_num=28 pso_serial#=68 2025-09-09 22:00:00.030701 :CB43101A:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=16724 osp_idx=26 osp_ver=308156 osp_pg=0 (spawn #575830) 2025-09-09 22:00:00.030702 :CB43101B:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:00:00.030702 :CB43101C:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=82 time=1840851330 2025-09-09 22:00:00.030703 :CB43101D:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:00:00.030703 :CB43101E:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:00:00.030703 :CB43101F:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:00:00.030704 :CB431020:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:00:00.030704 :CB431021:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:00:00.031204 :CB431022:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.031249 :CB431029:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09 22:00:00.031327 :CB43103D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.035169 :CB43104C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.036327 :CB431054:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.036351 :CB431057:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.036392 :CB431062:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-09 22:00:00.038222 :CB43106D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.038254 :CB431070:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.038310 :CB43107B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-09 22:00:00.038818 :CB431086:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.041339 :CB43108E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.046806 :CB431096:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.052356 :CB43109E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.053752 :CB4310A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.054845 :CB4310AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.057423 :CB4310B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.058827 :CB4310BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.061885 :CB4310C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.065453 :CB4310CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.065808 :CB4310DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.065859 :CB4310E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-09 22:00:00.065872 :CB4310E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-09 22:00:00.068014 :CB4310E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.068061 :CB4310EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-09 22:00:00.068072 :CB4310EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-09 22:00:00.070074 :CB4310F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.070125 :CB4310F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-09 22:00:00.070136 :CB4310F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-09 22:00:00.070309 :CB4310FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.070323 :CB4310FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-09 22:00:00.070381 :CB43110A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-09 22:00:00.072072 :CB431114:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-09 22:00:02.026118 :CB4311E1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-09 22:00:02.021 2025-09-09 22:00:02.026119 :CB4311E2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19952 pso_num=28 pso_serial#=69 2025-09-09 22:00:02.026258 :CB4311E3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=19952 osp_idx=26 osp_ver=308157 osp_pg=0 (spawn #575831) 2025-09-09 22:00:02.026259 :CB4311E4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:00:02.026260 :CB4311E5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=83 time=1840853330 2025-09-09 22:00:02.026260 :CB4311E6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:00:02.026260 :CB4311E7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:00:02.026261 :CB4311E8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:00:02.026261 :CB4311E9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:00:02.026261 :CB4311EA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:00:02.026771 :CB4311F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.031775 :CB43120E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.031816 :CB431211:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-09-09 22:00:02.048463 :CB431410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.048662 :CB431418:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.048835 :CB431420:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.048999 :CB431428:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.049176 :CB431430:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.049368 :CB431441:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.049519 :CB431457:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.049678 :CB43145F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.049836 :CB431467:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.050007 :CB43146F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.050157 :CB431477:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.050310 :CB43147F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.050557 :CB431488:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.050736 :CB431490:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.050886 :CB431498:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051046 :CB4314A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051203 :CB4314A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051359 :CB4314B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051517 :CB4314B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051690 :CB4314C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051833 :CB4314CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.051874 :CB4314D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.052096 :CB4314E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.052749 :CB4314F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.052930 :CB4314F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.053202 :CB431500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:02.080454 :CB431508:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.738830 :CB4317C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.763103 :CB4317D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.765371 :CB4317DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-09-09 22:00:03.765625 :CB4317DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-09-09 22:00:03.766707 :CB4317DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.766831 :CB4317E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.767903 :CB4317F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.769735 :CB4317FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.770085 :CB431803:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:03.833036*: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-09 22:00:03.833036*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-09 22:00:03.833036*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-09 22:00:03.833036*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-09 22:00:03.833036*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-09 22:00:03.833036*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-09 22:00:03.833036*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-09 22:00:03.900015 :CB431899:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:05.830117 :CB431A3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:05.830132 :CB431A3B:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0008.005.0000d400 2025-09-09 22:00:08.224173 :CB431BC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:08.225669 :CB431BCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:08.236250 :CB431BD4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-09 22:00:08.265770 :CB431BDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 19952, J002)