Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250427100853\orcl_j001_10608_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:37594M/63366M, Ph+PgF:45298M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 10608, image: ORACLE.EXE (J001) *** 2025-04-27 10:08:53.183 *** SESSION ID:(201.17617) 2025-04-27 10:08:53.183 *** 2025-04-27 10:08:53.183 Process diagnostic dump for ORACLE.EXE (J001), OS id=10608, pid: 25, proc_ser: 90, sid: 201, sess_ser: 17617 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10608 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.146642 sec, exc=0.146642 sec, total=0.146642 sec wait times: max=0.500000 sec, heur=2.142446 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.495776 sec, exc=0.495776 sec, total=0.495776 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 17617 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 10:08:51 - 10:08:53] idle wait at each sample [session created at: 10:08:51] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-27 10:08:53.183 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 10608, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-27 10:01:00.504586 :8F693BD2: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-04-27 10:01:00.504623 :8F693BD5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:01:00.505447 :8F693BDB: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-04-27 10:01:00.505570 :8F693BE4: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-04-27 10:01:30.622936 :8F6946AA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:02:00.599255 :8F6950FE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:02:00.588 2025-04-27 10:02:00.599257 :8F6950FF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10584 pso_num=25 pso_serial#=82 2025-04-27 10:02:00.599393 :8F695100:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10584 osp_idx=23 osp_ver=66381 osp_pg=0 (spawn #123312) 2025-04-27 10:02:00.599394 :8F695101:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:02:00.599394 :8F695102:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=3018922390 2025-04-27 10:02:00.599395 :8F695103:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:02:00.599395 :8F695104:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:02:00.599396 :8F695105:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:02:00.599396 :8F695106:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:02:00.599396 :8F695107:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:02:00.599865 :8F695108: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-04-27 10:02:30.698220 :8F695B0E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:03:00.703496 :8F696556:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:03:00.704 2025-04-27 10:03:00.703497 :8F696557:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9780 pso_num=25 pso_serial#=83 2025-04-27 10:03:00.703625 :8F696558:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9780 osp_idx=23 osp_ver=66382 osp_pg=0 (spawn #123315) 2025-04-27 10:03:00.703626 :8F696559:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:03:00.703626 :8F69655A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=132 time=3018982484 2025-04-27 10:03:00.703627 :8F69655B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:03:00.703627 :8F69655C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:03:00.703627 :8F69655D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:03:00.703627 :8F69655E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:03:00.703628 :8F69655F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:03:00.704085 :8F696560: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-04-27 10:03:30.796973 :8F696FDC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:04:00.785742 :8F6979D5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:04:00.775 2025-04-27 10:04:00.785744 :8F6979D6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9344 pso_num=25 pso_serial#=84 2025-04-27 10:04:00.785874 :8F6979D7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9344 osp_idx=23 osp_ver=66383 osp_pg=0 (spawn #123318) 2025-04-27 10:04:00.785875 :8F6979D8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:04:00.785876 :8F6979D9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=133 time=3019042578 2025-04-27 10:04:00.785876 :8F6979DA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:04:00.785877 :8F6979DB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:04:00.785877 :8F6979DC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:04:00.785877 :8F6979DD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:04:00.785878 :8F6979DE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:04:00.786346 :8F6979DF: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-04-27 10:04:30.857167 :8F6983ED:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:05:00.873355 :8F698E03:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:05:00.861 2025-04-27 10:05:00.873358 :8F698E04:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6532 pso_num=25 pso_serial#=85 2025-04-27 10:05:00.873496 :8F698E05:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6532 osp_idx=23 osp_ver=66384 osp_pg=0 (spawn #123320) 2025-04-27 10:05:00.873496 :8F698E06:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:05:00.873497 :8F698E07:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=134 time=3019102656 2025-04-27 10:05:00.873497 :8F698E08:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:05:00.873498 :8F698E09:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:05:00.873498 :8F698E0A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:05:00.873498 :8F698E0B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:05:00.873498 :8F698E0C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:05:00.873968 :8F698E0D: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-04-27 10:05:30.956031 :8F6998F7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:06:00.978540 :8F69A882:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:06:00.967 2025-04-27 10:06:00.978543 :8F69A883:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10360 pso_num=25 pso_serial#=86 2025-04-27 10:06:00.978671 :8F69A884:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10360 osp_idx=23 osp_ver=66385 osp_pg=0 (spawn #123322) 2025-04-27 10:06:00.978671 :8F69A885:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:06:00.978672 :8F69A886:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=135 time=3019162765 2025-04-27 10:06:00.978672 :8F69A887:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:06:00.978672 :8F69A888:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:06:00.978673 :8F69A889:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:06:00.978673 :8F69A88A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:06:00.978673 :8F69A88B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:06:00.979171 :8F69A88C: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-04-27 10:06:31.095545 :8F69B2C0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:07:01.066848 :8F69BF50:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:07:01.065 2025-04-27 10:07:01.066850 :8F69BF51:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11000 pso_num=25 pso_serial#=87 2025-04-27 10:07:01.066971 :8F69BF52:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11000 osp_idx=23 osp_ver=66386 osp_pg=0 (spawn #123324) 2025-04-27 10:07:01.066971 :8F69BF53:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:07:01.066972 :8F69BF54:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=136 time=3019222859 2025-04-27 10:07:01.066972 :8F69BF55:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:07:01.066973 :8F69BF56:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:07:01.066973 :8F69BF57:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:07:01.066973 :8F69BF58:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:07:01.066973 :8F69BF59:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:07:01.067472 :8F69BF5A: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-04-27 10:07:31.152046 :8F69C976:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:08:01.155745 :8F69D38E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:08:01.145 2025-04-27 10:08:01.155747 :8F69D38F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9500 pso_num=25 pso_serial#=88 2025-04-27 10:08:01.155886 :8F69D390:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9500 osp_idx=23 osp_ver=66387 osp_pg=0 (spawn #123326) 2025-04-27 10:08:01.155887 :8F69D391:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:08:01.155887 :8F69D392:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=137 time=3019282953 2025-04-27 10:08:01.155888 :8F69D393:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:08:01.155888 :8F69D394:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:08:01.155888 :8F69D395:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:08:01.155889 :8F69D396:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:08:01.155889 :8F69D397:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:08:01.156365 :8F69D398: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-04-27 10:08:31.221249 :8F69DE80:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:08:49.173451 :8F69E47E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:08:49.161 2025-04-27 10:08:49.173454 :8F69E47F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7712 pso_num=25 pso_serial#=89 2025-04-27 10:08:49.173590 :8F69E480:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7712 osp_idx=23 osp_ver=66388 osp_pg=0 (spawn #123328) 2025-04-27 10:08:49.173591 :8F69E481:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:08:49.173592 :8F69E482:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=138 time=3019330968 2025-04-27 10:08:49.173592 :8F69E483:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:08:49.173593 :8F69E484:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:08:49.173593 :8F69E485:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:08:49.173593 :8F69E486:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:08:49.173594 :8F69E487:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:08:49.174105 :8F69E488: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-04-27 10:08:49.174147 :8F69E48E: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-04-27 10:08:49.178105 :8F69E4A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-27 10:08:49.178166 :8F69E4A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-27 10:08:49.178414 :8F69E4A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-27 10:08:49.178431 :8F69E4A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-27 10:08:49.178649 :8F69E4A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-27 10:08:49.178662 :8F69E4AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-27 10:08:49.178864 :8F69E4AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-27 10:08:49.178876 :8F69E4AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-27 10:08:49.179076 :8F69E4AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-27 10:08:49.179088 :8F69E4AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-27 10:08:49.179295 :8F69E4AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-04-27 10:08:49.179308 :8F69E4B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-04-27 10:08:49.181754 :8F69E4E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-27 10:08:49.277714 :8F69E619:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-27 10:08:49.270 2025-04-27 10:08:49.277716 :8F69E61A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10608 pso_num=25 pso_serial#=90 2025-04-27 10:08:49.277840 :8F69E61B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10608 osp_idx=23 osp_ver=66389 osp_pg=0 (spawn #123331) 2025-04-27 10:08:49.277840 :8F69E61C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 10:08:49.277841 :8F69E61D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=139 time=3019331078 2025-04-27 10:08:49.277842 :8F69E61E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 10:08:49.277842 :8F69E61F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 10:08:49.277843 :8F69E620:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 10:08:49.277843 :8F69E621:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 10:08:49.277843 :8F69E622:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 10:08:49.278403 :8F69E627: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-04-27 10:08:49.380658 :8F69E6F3: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-04-27 10:08:49.385874 :8F69E70E: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-04-27 10:08:49.385916 :8F69E711:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-04-27 10:08:49.389723 :8F69E721: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-04-27 10:08:49.494415 :8F69E729: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-04-27 10:08:49.619690 :8F69E731: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-04-27 10:08:49.744114 :8F69E739: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-04-27 10:08:50.876295 :8F69E7A3: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-04-27 10:08:50.877264 :8F69E7AB: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-04-27 10:08:50.878927 :8F69E7B3: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-04-27 10:08:50.880445 :8F69E7BB: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-04-27 10:08:50.883557 :8F69E7C3: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-04-27 10:08:50.885058 :8F69E7CB: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-04-27 10:08:50.900273 :8F69E7D3: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-04-27 10:08:50.900888 :8F69E7DB: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-04-27 10:08:50.901400 :8F69E7E3: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-04-27 10:08:50.901905 :8F69E7EB: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-04-27 10:08:50.904577 :8F69E7F3: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-04-27 10:08:50.905474 :8F69E7FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.906226 :8F69E7FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.906983 :8F69E7FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.907375 :8F69E7FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.907381 :8F69E7FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.908037 :8F69E800:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.908542 :8F69E801:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.908852 :8F69E802:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.909913 :8F69E803:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.936678 :8F69E804: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-04-27 10:08:50.936737 :8F69E807:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.937663 :8F69E80D: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-04-27 10:08:50.938702 :8F69E815:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.939202 :8F69E816: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-04-27 10:08:50.959648 :8F69E81E: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-04-27 10:08:50.959716 :8F69E821:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.960679 :8F69E827: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-04-27 10:08:50.973688 :8F69E82F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:50.974250 :8F69E830: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-04-27 10:08:50.995029 :8F69E840: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-04-27 10:08:50.995100 :8F69E843:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:50.996127 :8F69E849: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-04-27 10:08:50.997121 :8F69E851:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:51.018282 :8F69E889: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-04-27 10:08:51.018360 :8F69E88C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:51.019336 :8F69E892: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-04-27 10:08:51.020298 :8F69E89A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-04-27 10:08:51.020797 :8F69E89B: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-04-27 10:08:51.037813 :8F69E8A3: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-04-27 10:08:51.037870 :8F69E8A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-04-27 10:08:51.038768 :8F69E8AC: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-04-27 10:08:51.039229 :8F69E8B4: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-04-27 10:08:51.039561 :8F69E8BC: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-04-27 10:08:51.039740 :8F69E8C4: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-04-27 10:08:51.039867 :8F69E8CC: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-04-27 10:08:51.040003 :8F69E8D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-04-27 10:08:51.044069 :8F69E8D5: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-04-27 10:08:51.044143 :8F69E8D8: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-04-27 10:08:51.044254 :8F69E8E5: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 10608, J001)