Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250613220004\orcl_j000_16660_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:36709M/63366M, Ph+PgF:43188M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16660, image: ORACLE.EXE (J000) *** 2025-06-13 22:00:04.791 *** SESSION ID:(201.18575) 2025-06-13 22:00:04.791 *** 2025-06-13 22:00:04.791 Process diagnostic dump for ORACLE.EXE (J000), OS id=16660, pid: 25, proc_ser: 181, sid: 201, sess_ser: 18575 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16660 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.286689 sec, exc=0.286689 sec, total=0.286689 sec wait times: max=0.500000 sec, heur=4.240539 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.000015 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499205 sec, exc=0.499205 sec, total=0.499205 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.453914 sec, exc=0.453914 sec, total=0.453914 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510116 sec, exc=0.510116 sec, total=0.510116 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.490555 sec, exc=0.490555 sec, total=0.490555 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 18575 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00:00 - 22:00:04] 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-06-13 22:00:04.791 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16660, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-13 21:51:04.649568 :A44B30AA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:51:04.649568 :A44B30AB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:51:04.649569 :A44B30AC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:51:04.650043 :A44B30AD: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-06-13 21:51:34.753436 :A44B3A6D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:52:04.707522 :A44B443B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:52:04.705 2025-06-13 21:52:04.707524 :A44B443C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10280 pso_num=25 pso_serial#=172 2025-06-13 21:52:04.707660 :A44B443D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10280 osp_idx=23 osp_ver=155579 osp_pg=0 (spawn #282452) 2025-06-13 21:52:04.707661 :A44B443E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:52:04.707661 :A44B443F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=2827267891 2025-06-13 21:52:04.707662 :A44B4440:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:52:04.707662 :A44B4441:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:52:04.707662 :A44B4442:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:52:04.707663 :A44B4443:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:52:04.707663 :A44B4444:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:52:04.708146 :A44B4445: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-06-13 21:52:34.791466 :A44B4E0A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:53:04.803102 :A44B5741:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:53:04.792 2025-06-13 21:53:04.803104 :A44B5742:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13532 pso_num=25 pso_serial#=173 2025-06-13 21:53:04.803235 :A44B5743:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13532 osp_idx=23 osp_ver=155580 osp_pg=0 (spawn #282454) 2025-06-13 21:53:04.803236 :A44B5744:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:53:04.803237 :A44B5745:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=2827327985 2025-06-13 21:53:04.803237 :A44B5746:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:53:04.803237 :A44B5747:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:53:04.803238 :A44B5748:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:53:04.803239 :A44B5749:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:53:04.803240 :A44B574A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:53:04.803725 :A44B574B: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-06-13 21:53:34.873386 :A44B615F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:54:04.888366 :A44B6AE7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:54:04.877 2025-06-13 21:54:04.888368 :A44B6AE8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16308 pso_num=25 pso_serial#=174 2025-06-13 21:54:04.888495 :A44B6AE9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16308 osp_idx=23 osp_ver=155581 osp_pg=0 (spawn #282457) 2025-06-13 21:54:04.888496 :A44B6AEA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:54:04.888496 :A44B6AEB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=2827388079 2025-06-13 21:54:04.888497 :A44B6AEC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:54:04.888497 :A44B6AED:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:54:04.888497 :A44B6AEE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:54:04.888498 :A44B6AEF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:54:04.888498 :A44B6AF0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:54:04.888962 :A44B6AF1: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-06-13 21:54:34.975077 :A44B7479:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:55:04.978751 :A44B7E0B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:55:04.967 2025-06-13 21:55:04.978752 :A44B7E0C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16340 pso_num=25 pso_serial#=175 2025-06-13 21:55:04.978889 :A44B7E0D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16340 osp_idx=23 osp_ver=155582 osp_pg=0 (spawn #282459) 2025-06-13 21:55:04.978890 :A44B7E0E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:55:04.978890 :A44B7E0F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=2827448157 2025-06-13 21:55:04.978891 :A44B7E10:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:55:04.978891 :A44B7E11:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:55:04.978891 :A44B7E12:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:55:04.978892 :A44B7E13:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:55:04.978892 :A44B7E14:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:55:04.979353 :A44B7E15: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-06-13 21:55:35.073493 :A44B8805:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:56:05.059788 :A44B91A5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:56:05.049 2025-06-13 21:56:05.059790 :A44B91A6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15624 pso_num=25 pso_serial#=176 2025-06-13 21:56:05.059920 :A44B91A7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15624 osp_idx=23 osp_ver=155583 osp_pg=0 (spawn #282461) 2025-06-13 21:56:05.059920 :A44B91A8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:56:05.059921 :A44B91A9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=2827508250 2025-06-13 21:56:05.059921 :A44B91AA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:56:05.059921 :A44B91AB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:56:05.059922 :A44B91AC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:56:05.059922 :A44B91AD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:56:05.059922 :A44B91AE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:56:05.060468 :A44B91AF: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-06-13 21:56:30.084704 :A44B997D: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-06-13 21:56:30.084742*:A44B997F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDBB58 estart=1749822989 eid=16908011 ctx=0x000000077BD98A08 cwh=0x0000000026B901B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-13 21:56:30.084742*:A44B9980:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 pubTabIdxCnt=0 2025-06-13 21:56:30.084742*:A44B9981:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDBB58 2025-06-13 21:56:30.084742*:A44B9982:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDBB58 2025-06-13 21:56:30.084742*:A44B9983:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDBB58 curCtx=0x0000000026B9F708 xsc->flg4=65792 flags=2 2025-06-13 21:56:30.084742*:A44B9984:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDBB58 2025-06-13 21:56:30.085380 :A44B9985: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-06-13 21:56:30.090764 :A44B998D: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-06-13 21:56:30.091728 :A44B9995: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-06-13 21:56:30.091854 :A44B999E: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-06-13 21:57:00.187566 :A44BA30C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:57:05.161053 :A44BA4FC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:57:05.150 2025-06-13 21:57:05.161055 :A44BA4FD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17004 pso_num=25 pso_serial#=177 2025-06-13 21:57:05.161187 :A44BA4FE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17004 osp_idx=23 osp_ver=155584 osp_pg=0 (spawn #282463) 2025-06-13 21:57:05.161188 :A44BA4FF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:57:05.161188 :A44BA500:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=2827568344 2025-06-13 21:57:05.161189 :A44BA501:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:57:05.161189 :A44BA502:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:57:05.161190 :A44BA503:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:57:05.161190 :A44BA504:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:57:05.161190 :A44BA505:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:57:05.161726 :A44BA506: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-06-13 21:57:35.243957 :A44BAE82:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:58:05.269444 :A44BB82B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:58:05.258 2025-06-13 21:58:05.269446 :A44BB82C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17000 pso_num=25 pso_serial#=178 2025-06-13 21:58:05.269580 :A44BB82D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17000 osp_idx=23 osp_ver=155585 osp_pg=0 (spawn #282465) 2025-06-13 21:58:05.269580 :A44BB82E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:58:05.269581 :A44BB82F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=2827628454 2025-06-13 21:58:05.269581 :A44BB830:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:58:05.269582 :A44BB831:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:58:05.269582 :A44BB832:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:58:05.269582 :A44BB833:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:58:05.269583 :A44BB834:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:58:05.270041 :A44BB835: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-06-13 21:58:35.389493 :A44BC201:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 21:59:05.343279 :A44BCB85:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 21:59:05.332 2025-06-13 21:59:05.343281 :A44BCB86:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16972 pso_num=25 pso_serial#=179 2025-06-13 21:59:05.343419 :A44BCB87:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16972 osp_idx=23 osp_ver=155586 osp_pg=0 (spawn #282468) 2025-06-13 21:59:05.343419 :A44BCB88:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 21:59:05.343420 :A44BCB89:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=2827688532 2025-06-13 21:59:05.343420 :A44BCB8A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 21:59:05.343421 :A44BCB8B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 21:59:05.343421 :A44BCB8C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 21:59:05.343422 :A44BCB8D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 21:59:05.343422 :A44BCB8E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 21:59:05.343902 :A44BCB8F: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-06-13 21:59:35.401905 :A44BD517:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 22:00:00.038520 :A44BDD02:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 22:00:00.029 2025-06-13 22:00:00.038522 :A44BDD03:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16368 pso_num=25 pso_serial#=180 2025-06-13 22:00:00.038665 :A44BDD04:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16368 osp_idx=23 osp_ver=155587 osp_pg=0 (spawn #282470) 2025-06-13 22:00:00.038666 :A44BDD05:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:00:00.038666 :A44BDD06:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=2827743219 2025-06-13 22:00:00.038667 :A44BDD07:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:00:00.038667 :A44BDD08:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:00:00.038667 :A44BDD09:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:00:00.038668 :A44BDD0A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:00:00.038668 :A44BDD0B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:00:00.039173 :A44BDD0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:00:00.039216 :A44BDD12:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-13 22:00:00.039293 :A44BDD27: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-06-13 22:00:00.043195 :A44BDD36: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-06-13 22:00:00.044339 :A44BDD3E: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-06-13 22:00:00.044368 :A44BDD41: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-06-13 22:00:00.044412 :A44BDD4C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-13 22:00:00.046280 :A44BDD57: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-06-13 22:00:00.046307 :A44BDD5A: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-06-13 22:00:00.046344 :A44BDD65:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-13 22:00:00.046800 :A44BDD70: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-06-13 22:00:00.049278 :A44BDD78: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-06-13 22:00:00.054606 :A44BDD80: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-06-13 22:00:00.058620 :A44BDD88: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-06-13 22:00:00.060362 :A44BDD90: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-06-13 22:00:00.061840 :A44BDD98: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-06-13 22:00:00.062769 :A44BDDA0: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-06-13 22:00:00.065319 :A44BDDA8: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-06-13 22:00:00.066595 :A44BDDB0: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-06-13 22:00:00.069594 :A44BDDB8: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-06-13 22:00:00.072954 :A44BDDC0: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-06-13 22:00:00.073296 :A44BDDCF: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-06-13 22:00:00.073343 :A44BDDD2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-13 22:00:00.073359 :A44BDDD3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-13 22:00:00.075440 :A44BDDD9: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-06-13 22:00:00.075488 :A44BDDDC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-13 22:00:00.075499 :A44BDDDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-13 22:00:00.077569 :A44BDDE3: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-06-13 22:00:00.077613 :A44BDDE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-06-13 22:00:00.077624 :A44BDDE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-06-13 22:00:00.077790 :A44BDDED: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-06-13 22:00:00.077805 :A44BDDF0: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-06-13 22:00:00.077842 :A44BDDFB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-13 22:00:00.079508 :A44BDE06:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-13 22:00:00.410179 :A44BDE21:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-13 22:00:00.404 2025-06-13 22:00:00.410182 :A44BDE22:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16660 pso_num=25 pso_serial#=181 2025-06-13 22:00:00.410313 :A44BDE23:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16660 osp_idx=23 osp_ver=155588 osp_pg=0 (spawn #282471) 2025-06-13 22:00:00.410314 :A44BDE24:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:00:00.410314 :A44BDE25:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=2827743594 2025-06-13 22:00:00.410315 :A44BDE26:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:00:00.410315 :A44BDE27:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:00:00.410316 :A44BDE28:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:00:00.410316 :A44BDE29:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:00:00.410316 :A44BDE2A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:00:00.410849 :A44BDE2B: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-06-13 22:00:00.416223 :A44BDE4F: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-06-13 22:00:00.416269 :A44BDE5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-13 22:00:00.427270 :A44BDEAB: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-06-13 22:00:00.432589 :A44BDECA: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-06-13 22:00:00.433362 :A44BDEEA: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-06-13 22:00:00.439094 :A44BE059: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-06-13 22:00:00.439255 :A44BE068: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-06-13 22:00:00.439952 :A44BE084: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-06-13 22:00:00.440140 :A44BE08C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-13 22:00:00.441633 :A44BE08D: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-06-13 22:00:00.441670 :A44BE090: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-06-13 22:00:00.441741 :A44BE09D: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-06-13 22:00:00.545687 :A44BE1A4: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-06-13 22:00:00.547732 :A44BE1A8: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-06-13 22:00:00.547771 :A44BE1AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-06-13 22:00:00.550268 :A44BE1B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-06-13 22:00:00.553477 :A44BE1B2: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-06-13 22:00:00.553556 :A44BE1B5: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-06-13 22:00:00.553639 :A44BE1C2: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-06-13 22:00:04.008879 :A44BE6C2: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: 16660, J000)