Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250821220008\orcl_j003_22388_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:35295M/63366M, Ph+PgF:40459M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 22388, image: ORACLE.EXE (J003) *** 2025-08-21 22:00:08.746 *** SESSION ID:(233.32557) 2025-08-21 22:00:08.746 *** 2025-08-21 22:00:08.746 Process diagnostic dump for ORACLE.EXE (J003), OS id=22388, pid: 29, proc_ser: 147, sid: 233, sess_ser: 32557 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22388 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12951, hash value=227935524 Current Wait Stack: Not in wait; last wait ended 0.963602 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.963628 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.000357 sec, exc=0.000357 sec, total=0.000357 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#=0x29ed, block#=0x0, blocks=0x0 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.046801 sec of elapsed time 2: waited for 'log file sequential read' log#=0x29ed, block#=0x0, blocks=0x0 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x7000, blocks=0x800 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000287 sec, exc=0.000287 sec, total=0.000287 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x6800, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000310 sec, exc=0.000310 sec, total=0.000310 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x6000, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000293 sec, exc=0.000293 sec, total=0.000293 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x5800, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000286 sec, exc=0.000286 sec, total=0.000286 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x5000, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000284 sec, exc=0.000284 sec, total=0.000284 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0x4800, blocks=0x800 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000297 sec, exc=0.000297 sec, total=0.000297 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000036 sec of elapsed time 9: waited for 'log file sequential read' log#=0x0, block#=0x4000, blocks=0x800 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000334 sec, exc=0.000334 sec, total=0.000334 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.511979 sec of elapsed time Sampled Session History of session 233 serial 32557 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:00:02 - 22:00:08] 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-08-21 22:00:08.746 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 22388, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-21 01:12:56.987254 :C27BD980:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 01:12:56.987255 :C27BD981:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 01:12:56.987255 :C27BD982:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 01:12:56.987256 :C27BD983:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 01:12:56.987783 :C27BD984: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-08-21 01:12:56.987829 :C27BD98A: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-08-21 01:12:56.991955 :C27BD9A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:12:56.992006 :C27BD9A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:12:56.992269 :C27BD9A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:12:56.992285 :C27BD9A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:12:56.992500 :C27BD9A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:12:56.992513 :C27BD9A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:12:56.992720 :C27BD9A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:12:56.992733 :C27BD9AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:12:56.992939 :C27BD9AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:12:56.992952 :C27BD9AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:12:56.993161 :C27BD9AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:12:56.993174 :C27BD9AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:12:56.995741 :C27BD9AF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 01:43:00.915625 :C27E277D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 01:43:00.903 2025-08-21 01:43:00.915627 :C27E277E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19376 pso_num=29 pso_serial#=140 2025-08-21 01:43:00.915763 :C27E277F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19376 osp_idx=27 osp_ver=16204 osp_pg=0 (spawn #509673) 2025-08-21 01:43:00.915764 :C27E2780:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 01:43:00.915764 :C27E2781:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=66 time=126282924 2025-08-21 01:43:00.915765 :C27E2782:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 01:43:00.915765 :C27E2783:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 01:43:00.915765 :C27E2784:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 01:43:00.915766 :C27E2785:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 01:43:00.915766 :C27E2786:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 01:43:00.916340 :C27E2787: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-08-21 01:43:00.916390 :C27E278E: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-08-21 01:43:00.920456 :C27E27B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:43:00.920517 :C27E27B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:43:00.920824 :C27E27B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:43:00.920843 :C27E27B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:43:00.921069 :C27E27BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:43:00.921083 :C27E27BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:43:00.921296 :C27E27BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:43:00.921309 :C27E27BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:43:00.921521 :C27E27BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:43:00.921535 :C27E27BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:43:00.921742 :C27E27C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-21 01:43:00.921756 :C27E27C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-21 01:43:00.924100 :C27E27C2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 13:04:30.678434 :C2B2FC7A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 13:04:30.666 2025-08-21 13:04:30.678435 :C2B2FC7B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22040 pso_num=29 pso_serial#=141 2025-08-21 13:04:30.678574 :C2B2FC7C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22040 osp_idx=27 osp_ver=16205 osp_pg=0 (spawn #511247) 2025-08-21 13:04:30.678575 :C2B2FC7D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 13:04:30.678575 :C2B2FC7E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=67 time=167172674 2025-08-21 13:04:30.678576 :C2B2FC7F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 13:04:30.678576 :C2B2FC80:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 13:04:30.678577 :C2B2FC81:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 13:04:30.678577 :C2B2FC82:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 13:04:30.678577 :C2B2FC83:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 13:04:30.679112 :C2B2FC84: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-08-21 13:04:30.679159 :C2B2FC8A: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-08-21 13:04:30.687728 :C2B2FC9F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 17:00:01.910814 :C2C4FCFB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 17:00:01.907 2025-08-21 17:00:01.910815 :C2C4FCFC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20576 pso_num=29 pso_serial#=142 2025-08-21 17:00:01.910955 :C2C4FCFD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20576 osp_idx=27 osp_ver=16206 osp_pg=0 (spawn #511788) 2025-08-21 17:00:01.910956 :C2C4FCFE:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 17:00:01.910956 :C2C4FCFF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=68 time=181303908 2025-08-21 17:00:01.910957 :C2C4FD00:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 17:00:01.910957 :C2C4FD01:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 17:00:01.910957 :C2C4FD02:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 17:00:01.910958 :C2C4FD03:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 17:00:01.910958 :C2C4FD04:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 17:00:01.911484 :C2C4FD05: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-08-21 17:00:01.911536 :C2C4FD0B: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-08-21 17:00:01.919820 :C2C4FD30:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 18:00:09.869340 :C2C9954A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 18:00:09.867 2025-08-21 18:00:09.869340 :C2C9954B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21252 pso_num=29 pso_serial#=143 2025-08-21 18:00:09.869490 :C2C9954C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21252 osp_idx=27 osp_ver=16207 osp_pg=0 (spawn #511927) 2025-08-21 18:00:09.869491 :C2C9954D:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 18:00:09.869492 :C2C9954E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=69 time=184911862 2025-08-21 18:00:09.869492 :C2C9954F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 18:00:09.869493 :C2C99550:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 18:00:09.869494 :C2C99551:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 18:00:09.869494 :C2C99552:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 18:00:09.869494 :C2C99553:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 18:00:09.870035 :C2C99554: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-08-21 18:00:09.870076 :C2C9955A: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-08-21 18:00:09.878641 :C2C99580:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 19:00:18.066512 :C2CE472F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 19:00:18.063 2025-08-21 19:00:18.066513 :C2CE4730:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14620 pso_num=29 pso_serial#=144 2025-08-21 19:00:18.066653 :C2CE4731:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14620 osp_idx=27 osp_ver=16208 osp_pg=0 (spawn #512065) 2025-08-21 19:00:18.066654 :C2CE4732:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 19:00:18.066654 :C2CE4733:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=70 time=188520065 2025-08-21 19:00:18.066655 :C2CE4734:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 19:00:18.066655 :C2CE4735:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 19:00:18.066656 :C2CE4736:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 19:00:18.066656 :C2CE4737:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 19:00:18.066656 :C2CE4738:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 19:00:18.067199 :C2CE4739: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-08-21 19:00:18.067250 :C2CE473F: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-08-21 19:00:18.076136 :C2CE4764:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 20:00:26.132536 :C2D2F1CB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 20:00:26.129 2025-08-21 20:00:26.132537 :C2D2F1CC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21196 pso_num=29 pso_serial#=145 2025-08-21 20:00:26.132669 :C2D2F1CD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21196 osp_idx=27 osp_ver=16209 osp_pg=0 (spawn #512204) 2025-08-21 20:00:26.132670 :C2D2F1CE:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 20:00:26.132670 :C2D2F1CF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=71 time=192128127 2025-08-21 20:00:26.132670 :C2D2F1D0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 20:00:26.132671 :C2D2F1D1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 20:00:26.132671 :C2D2F1D2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 20:00:26.132671 :C2D2F1D3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 20:00:26.132672 :C2D2F1D4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 20:00:26.133159 :C2D2F1D5: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-08-21 20:00:26.133205 :C2D2F1DB: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-08-21 20:00:26.141789 :C2D2F1F0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 21:00:33.937429 :C2D7AA4D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 21:00:33.934 2025-08-21 21:00:33.937431 :C2D7AA4E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22436 pso_num=29 pso_serial#=146 2025-08-21 21:00:33.937566 :C2D7AA4F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22436 osp_idx=27 osp_ver=16210 osp_pg=0 (spawn #512341) 2025-08-21 21:00:33.937567 :C2D7AA50:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:00:33.937567 :C2D7AA51:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=72 time=195735940 2025-08-21 21:00:33.937567 :C2D7AA52:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:00:33.937568 :C2D7AA53:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:00:33.937568 :C2D7AA54:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:00:33.937569 :C2D7AA55:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:00:33.937569 :C2D7AA56:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:00:33.938092 :C2D7AA57: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-08-21 21:00:33.938135 :C2D7AA5D: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-08-21 21:00:33.946325 :C2D7AA72:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-21 22:00:02.050628 :C2DC5751:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-21 22:00:02.043 2025-08-21 22:00:02.050630 :C2DC5752:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22388 pso_num=29 pso_serial#=147 2025-08-21 22:00:02.050770 :C2DC5756:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22388 osp_idx=27 osp_ver=16211 osp_pg=0 (spawn #512481) 2025-08-21 22:00:02.050771 :C2DC5757:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 22:00:02.050771 :C2DC5758:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=73 time=199304065 2025-08-21 22:00:02.050772 :C2DC5759:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 22:00:02.050772 :C2DC575A:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 22:00:02.050773 :C2DC575B:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 22:00:02.050773 :C2DC575C:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 22:00:02.050773 :C2DC575D:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 22:00:02.051283 :C2DC5767: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-08-21 22:00:02.056393 :C2DC578E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-21 22:00:02.074072 :C2DC5968: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-08-21 22:00:02.074424 :C2DC5970: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-08-21 22:00:02.074597 :C2DC5978: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-08-21 22:00:02.074779 :C2DC5980: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-08-21 22:00:02.075024 :C2DC5988: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-08-21 22:00:02.075183 :C2DC5990: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-08-21 22:00:02.075353 :C2DC5998: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-08-21 22:00:02.075525 :C2DC59A0: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-08-21 22:00:02.075682 :C2DC59A8: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-08-21 22:00:02.075849 :C2DC59B0: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-08-21 22:00:02.076005 :C2DC59B8: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-08-21 22:00:02.076169 :C2DC59C0: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-08-21 22:00:02.076327 :C2DC59C8: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-08-21 22:00:02.076475 :C2DC59D0: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-08-21 22:00:02.076722 :C2DC59D8: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-08-21 22:00:02.076908 :C2DC59E1: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-08-21 22:00:02.077058 :C2DC59E9: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-08-21 22:00:02.077218 :C2DC59F1: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-08-21 22:00:02.077626 :C2DC59F9: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-08-21 22:00:02.077804 :C2DC5A01: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-08-21 22:00:02.077955 :C2DC5A0B: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-08-21 22:00:02.078200 :C2DC5A13: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-08-21 22:00:02.078448 :C2DC5A1B: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-08-21 22:00:02.078686 :C2DC5A32: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-08-21 22:00:02.079301 :C2DC5A3A: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-08-21 22:00:02.105496 :C2DC5A42: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-08-21 22:00:03.749548 :C2DC5D13: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-08-21 22:00:03.770324 :C2DC5D35: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-08-21 22:00:03.772362 :C2DC5D3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-21 22:00:03.772571 :C2DC5D3E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-21 22:00:03.773483 :C2DC5D3F: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-08-21 22:00:03.773583 :C2DC5D42: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-08-21 22:00:03.774437 :C2DC5D54: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-08-21 22:00:03.775906 :C2DC5D64: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-08-21 22:00:03.776319 :C2DC5D6C: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-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-21 22:00:03.834542*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-21 22:00:03.916320 :C2DC5E03: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-08-21 22:00:05.791740 :C2DC5FBD: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-08-21 22:00:05.791752 :C2DC5FBE:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.012.0000d257 2025-08-21 22:00:08.241727 :C2DC6141: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-08-21 22:00:08.243164 :C2DC6149: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-08-21 22:00:08.253748 :C2DC6151: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-08-21 22:00:08.282565 :C2DC617C: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 22388, J003)