Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250708220007\orcl_j001_3836_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250708220007\orcl_j001_3836_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:36535M/63366M, Ph+PgF:42624M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 3836, image: ORACLE.EXE (J001) *** 2025-07-08 22:00:07.846 *** SESSION ID:(201.22653) 2025-07-08 22:00:07.846 *** 2025-07-08 22:00:07.846 Process diagnostic dump for ORACLE.EXE (J001), OS id=3836, pid: 25, proc_ser: 222, sid: 201, sess_ser: 22653 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3836 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.302474 sec, exc=0.302474 sec, total=0.302474 sec wait times: max=0.500000 sec, heur=7.526937 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500012 sec, exc=0.500012 sec, total=0.500012 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.510092 sec, exc=0.510092 sec, total=0.510092 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499211 sec, exc=0.499211 sec, total=0.499211 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.203906 sec, exc=0.203906 sec, total=0.203906 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499970 sec, exc=0.499970 sec, total=0.499970 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500030 sec, exc=0.500030 sec, total=0.500030 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 201 serial 22653 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:00 - 22:00:07] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-08 22:00:07.846 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 3836, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-08 21:46:28.808391 :AF614E96:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=145 time=691964689 2025-07-08 21:46:28.808391 :AF614E97:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:46:28.808392 :AF614E98:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:46:28.808392 :AF614E99:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:46:28.808392 :AF614E9A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:46:28.808393 :AF614E9B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:46:28.808889 :AF614E9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:46:58.900836 :AF61594D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:47:28.919580 :AF616313:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:47:28.919 2025-07-08 21:47:28.919582 :AF616314:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17472 pso_num=25 pso_serial#=210 2025-07-08 21:47:28.919730 :AF616315:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17472 osp_idx=23 osp_ver=188002 osp_pg=0 (spawn #365807) 2025-07-08 21:47:28.919731 :AF616316:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:47:28.919732 :AF616317:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=146 time=692024798 2025-07-08 21:47:28.919732 :AF616318:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:47:28.919732 :AF616319:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:47:28.919733 :AF61631A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:47:28.919733 :AF61631B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:47:28.919733 :AF61631C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:47:28.920231 :AF61631D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:47:59.034172 :AF616CC4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:48:29.051737 :AF6176D9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:48:29.040 2025-07-08 21:48:29.051739 :AF6176DA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11256 pso_num=25 pso_serial#=211 2025-07-08 21:48:29.051862 :AF6176DB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11256 osp_idx=23 osp_ver=188003 osp_pg=0 (spawn #365810) 2025-07-08 21:48:29.051863 :AF6176DC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:48:29.051863 :AF6176DD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=692084939 2025-07-08 21:48:29.051864 :AF6176DE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:48:29.051864 :AF6176DF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:48:29.051864 :AF6176E0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:48:29.051864 :AF6176E1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:48:29.051864 :AF6176E2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:48:29.052335 :AF6176E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:48:59.153405 :AF6180BC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:49:29.159850 :AF618AA7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:49:29.148 2025-07-08 21:49:29.159852 :AF618AA8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13928 pso_num=25 pso_serial#=212 2025-07-08 21:49:29.159973 :AF618AA9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13928 osp_idx=23 osp_ver=188004 osp_pg=0 (spawn #365812) 2025-07-08 21:49:29.159973 :AF618AAA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:49:29.159974 :AF618AAB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=692145048 2025-07-08 21:49:29.159974 :AF618AAC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:49:29.159975 :AF618AAD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:49:29.159975 :AF618AAE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:49:29.159975 :AF618AAF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:49:29.159976 :AF618AB0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:49:29.160466 :AF618AB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:49:54.177747 :AF61938C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-08 21:50:24.261610 :AF619D26:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:50:29.256704 :AF619EC5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:50:29.245 2025-07-08 21:50:29.256708 :AF619EC6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17508 pso_num=25 pso_serial#=213 2025-07-08 21:50:29.256830 :AF619EC7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17508 osp_idx=23 osp_ver=188005 osp_pg=0 (spawn #365814) 2025-07-08 21:50:29.256830 :AF619EC8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:50:29.256830 :AF619EC9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=692205142 2025-07-08 21:50:29.256831 :AF619ECA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:50:29.256831 :AF619ECB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:50:29.256831 :AF619ECC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:50:29.256832 :AF619ECD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:50:29.256832 :AF619ECE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:50:29.257312 :AF619ECF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:50:59.390521 :AF61A8D0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:51:29.347023 :AF61B2AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:51:29.335 2025-07-08 21:51:29.347025 :AF61B2AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3780 pso_num=25 pso_serial#=214 2025-07-08 21:51:29.347160 :AF61B2AF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3780 osp_idx=23 osp_ver=188006 osp_pg=0 (spawn #365816) 2025-07-08 21:51:29.347161 :AF61B2B0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:51:29.347161 :AF61B2B1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=692265236 2025-07-08 21:51:29.347162 :AF61B2B2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:51:29.347162 :AF61B2B3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:51:29.347163 :AF61B2B4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:51:29.347163 :AF61B2B5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:51:29.347163 :AF61B2B6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:51:29.347654 :AF61B2B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:51:59.450788 :AF61BCE5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:52:29.446821 :AF61C6CF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:52:29.436 2025-07-08 21:52:29.446823 :AF61C6D0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15668 pso_num=25 pso_serial#=215 2025-07-08 21:52:29.446957 :AF61C6D1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15668 osp_idx=23 osp_ver=188007 osp_pg=0 (spawn #365818) 2025-07-08 21:52:29.446958 :AF61C6D2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:52:29.446958 :AF61C6D3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=692325329 2025-07-08 21:52:29.446958 :AF61C6D4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:52:29.446959 :AF61C6D5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:52:29.446959 :AF61C6D6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:52:29.446959 :AF61C6D7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:52:29.446960 :AF61C6D8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:52:29.447446 :AF61C6D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:52:59.549485 :AF61D08B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:53:29.559062 :AF61DA3E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:53:29.548 2025-07-08 21:53:29.559064 :AF61DA3F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17744 pso_num=25 pso_serial#=216 2025-07-08 21:53:29.559207 :AF61DA40:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17744 osp_idx=23 osp_ver=188008 osp_pg=0 (spawn #365820) 2025-07-08 21:53:29.559207 :AF61DA41:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:53:29.559208 :AF61DA42:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=692385454 2025-07-08 21:53:29.559208 :AF61DA43:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:53:29.559209 :AF61DA44:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:53:29.559209 :AF61DA45:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:53:29.559209 :AF61DA46:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:53:29.559210 :AF61DA47:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:53:29.559703 :AF61DA48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:53:59.663294 :AF61E3FC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:54:29.657732 :AF61EDF9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:54:29.646 2025-07-08 21:54:29.657734 :AF61EDFA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7676 pso_num=25 pso_serial#=217 2025-07-08 21:54:29.657875 :AF61EDFB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7676 osp_idx=23 osp_ver=188009 osp_pg=0 (spawn #365822) 2025-07-08 21:54:29.657876 :AF61EDFC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:54:29.657876 :AF61EDFD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=692445548 2025-07-08 21:54:29.657876 :AF61EDFE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:54:29.657877 :AF61EDFF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:54:29.657877 :AF61EE00:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:54:29.657878 :AF61EE01:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:54:29.657878 :AF61EE02:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:54:29.658348 :AF61EE03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:54:59.791275 :AF61F80D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:55:29.752254 :AF620209:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:55:29.740 2025-07-08 21:55:29.752256 :AF62020A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18420 pso_num=25 pso_serial#=218 2025-07-08 21:55:29.752386 :AF62020B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18420 osp_idx=23 osp_ver=188010 osp_pg=0 (spawn #365824) 2025-07-08 21:55:29.752387 :AF62020C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:55:29.752387 :AF62020D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=692505642 2025-07-08 21:55:29.752388 :AF62020E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:55:29.752388 :AF62020F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:55:29.752389 :AF620210:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:55:29.752389 :AF620211:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:55:29.752389 :AF620212:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:55:29.752880 :AF620213:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:55:59.854645 :AF620C1B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:56:29.869774 :AF6215F3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:56:29.858 2025-07-08 21:56:29.869776 :AF6215F4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13156 pso_num=25 pso_serial#=219 2025-07-08 21:56:29.869904 :AF6215F5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13156 osp_idx=23 osp_ver=188011 osp_pg=0 (spawn #365827) 2025-07-08 21:56:29.869905 :AF6215F6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:56:29.869905 :AF6215F7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=692565751 2025-07-08 21:56:29.869905 :AF6215F8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:56:29.869906 :AF6215F9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:56:29.869906 :AF6215FA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:56:29.869906 :AF6215FB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:56:29.869907 :AF6215FC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:56:29.870398 :AF6215FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:56:59.952629 :AF62209C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:57:29.965683 :AF622A89:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:57:29.954 2025-07-08 21:57:29.965685 :AF622A8A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4140 pso_num=25 pso_serial#=220 2025-07-08 21:57:29.965811 :AF622A8B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4140 osp_idx=23 osp_ver=188012 osp_pg=0 (spawn #365829) 2025-07-08 21:57:29.965812 :AF622A8C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:57:29.965813 :AF622A8D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=692625861 2025-07-08 21:57:29.965813 :AF622A8E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:57:29.965813 :AF622A8F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:57:29.965813 :AF622A90:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:57:29.965814 :AF622A91:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:57:29.965814 :AF622A92:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:57:29.966306 :AF622A93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:58:00.075480 :AF623471:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:58:30.065426 :AF623EA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:58:30.054 2025-07-08 21:58:30.065428 :AF623EAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10876 pso_num=25 pso_serial#=221 2025-07-08 21:58:30.065554 :AF623EAB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10876 osp_idx=23 osp_ver=188013 osp_pg=0 (spawn #365833) 2025-07-08 21:58:30.065554 :AF623EAC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:58:30.065555 :AF623EAD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=692685954 2025-07-08 21:58:30.065555 :AF623EAE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:58:30.065556 :AF623EAF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:58:30.065556 :AF623EB0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:58:30.065556 :AF623EB1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:58:30.065557 :AF623EB2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:58:30.066040 :AF623EB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:59:00.186151 :AF6248EA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-08 21:59:30.163139 :AF625262:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-08 21:59:30.152 2025-07-08 21:59:30.163141 :AF625263:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3836 pso_num=25 pso_serial#=222 2025-07-08 21:59:30.163275 :AF625264:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3836 osp_idx=23 osp_ver=188014 osp_pg=0 (spawn #365835) 2025-07-08 21:59:30.163275 :AF625265:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 21:59:30.163276 :AF625266:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=692746048 2025-07-08 21:59:30.163276 :AF625267:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 21:59:30.163277 :AF625268:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 21:59:30.163277 :AF625269:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 21:59:30.163277 :AF62526A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 21:59:30.163277 :AF62526B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 21:59:30.163754 :AF62526C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 21:59:55.192044 :AF625B41:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-08 22:00:00.191339 :AF625E0A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-08 22:00:00.191339*:AF625E34:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1751983199 eid=16783815 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-08 22:00:00.191339*:AF625E36:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-07-08 22:00:00.191339*:AF625E38:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-07-08 22:00:00.191339*:AF625E39:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-07-08 22:00:00.191339*:AF625E4B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-07-08 22:00:00.191339*:AF625E4C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-07-08 22:00:00.198307 :AF625E4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.198379 :AF625E50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-08 22:00:00.210170 :AF625EAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.215572 :AF625ED3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.216393 :AF625EDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.222297 :AF625EEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.222454 :AF625EFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.223291 :AF625F02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.223516 :AF625F0A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-08 22:00:00.225087 :AF625F1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.316098 :AF6261D4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-08 22:00:00.317998 :AF6261E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.318032 :AF6261E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-07-08 22:00:00.320418 :AF6261EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-07-08 22:00:00.323655 :AF6261EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.323736 :AF6261F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.323818 :AF6261FF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-07-08 22:00:04.029352 :AF6266A9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 3836, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de