Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250908220006\orcl_j002_12488_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:34930M/63366M, Ph+PgF:39691M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 12488, image: ORACLE.EXE (J002) *** 2025-09-08 22:00:06.160 *** SESSION ID:(225.24093) 2025-09-08 22:00:06.160 *** 2025-09-08 22:00:06.160 Process diagnostic dump for ORACLE.EXE (J002), OS id=12488, pid: 28, proc_ser: 176, sid: 225, sess_ser: 24093 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12488 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.155355 sec, exc=0.155355 sec, total=0.155355 sec wait times: max=0.500000 sec, heur=4.097838 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.000005 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500114 sec, exc=0.500114 sec, total=0.500114 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499138 sec, exc=0.499138 sec, total=0.499138 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.346679 sec, exc=0.346679 sec, total=0.346679 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500238 sec, exc=0.500238 sec, total=0.500238 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=2 seq_num=3 snap_id=1 wait times: snap=0.510106 sec, exc=0.510106 sec, total=0.510106 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499273 sec, exc=0.499273 sec, total=0.499273 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.086860 sec, exc=0.086860 sec, total=0.086860 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 225 serial 24093 --------------------------------------------------- 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:02 - 22:00:06] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-08 22:00:06.160 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 12488, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-08 16:00:15.725759 :CAB623C9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 16:00:18.823780 :CAB624C4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 16:00:18.812 2025-09-08 16:00:18.823782 :CAB624C5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19768 pso_num=28 pso_serial#=163 2025-09-08 16:00:18.823929 :CAB624C6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19768 (legacy spawn) 2025-09-08 16:00:18.831006 :CAB624C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 16:00:18.831190 :CAB624CC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 16:00:18.832112 :CAB624D1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 16:00:18.841614 :CAB624D4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 16:00:18.828 2025-09-08 16:00:18.841616 :CAB624D5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22412 pso_num=28 pso_serial#=164 2025-09-08 16:00:18.841759 :CAB624D6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22412 (legacy spawn) 2025-09-08 16:00:18.848756 :CAB624D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 16:00:18.848936 :CAB624E0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 16:00:18.849861 :CAB624E1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 18:09:56.232834 :CAC07711:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 18:09:56.220 2025-09-08 18:09:56.232836 :CAC07712:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24408 pso_num=28 pso_serial#=165 2025-09-08 18:09:56.232978 :CAC07713:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=24408 osp_idx=26 osp_ver=306723 osp_pg=0 (spawn #571962) 2025-09-08 18:09:56.232979 :CAC07714:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 18:09:56.232979 :CAC07715:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=6 time=1740647533 2025-09-08 18:09:56.232980 :CAC07716:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 18:09:56.232980 :CAC07717:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 18:09:56.232980 :CAC07718:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 18:09:56.232981 :CAC07719:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 18:09:56.232981 :CAC0771A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 18:09:56.233509 :CAC0771B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 18:09:56.233549 :CAC07721:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 18:09:56.237620 :CAC0773A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-08 18:09:56.237671 :CAC0773B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-08 18:09:56.237926 :CAC0773C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-08 18:09:56.237941 :CAC0773D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-08 18:09:56.238157 :CAC0773E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-08 18:09:56.238171 :CAC0773F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-08 18:09:56.238382 :CAC07740:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-08 18:09:56.238395 :CAC07741:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-08 18:09:56.238601 :CAC07742:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-08 18:09:56.238614 :CAC07743:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-08 18:09:56.238815 :CAC07744:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-08 18:09:56.238828 :CAC07745:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-08 18:09:56.241374 :CAC07746:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:25.409019 :CAC7A895:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:25.400 2025-09-08 19:42:25.409021 :CAC7A896:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16548 pso_num=28 pso_serial#=166 2025-09-08 19:42:25.409165 :CAC7A897:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16548 (legacy spawn) 2025-09-08 19:42:25.415811 :CAC7A898:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:25.415997 :CAC7A89D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:25.416865 :CAC7A8CA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:27.429711 :CAC7A905:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:27.420 2025-09-08 19:42:27.429713 :CAC7A906:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18376 pso_num=28 pso_serial#=167 2025-09-08 19:42:27.429859 :CAC7A907:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18376 (legacy spawn) 2025-09-08 19:42:27.785519 :CAC7A908:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:27.785721 :CAC7A90D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:27.786645 :CAC7A912:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:30.573549 :CAC7A9F9:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:30.558 2025-09-08 19:42:30.573550 :CAC7A9FA:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22176 pso_num=28 pso_serial#=168 2025-09-08 19:42:30.573694 :CAC7A9FB:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22176 (legacy spawn) 2025-09-08 19:42:30.580088 :CAC7A9FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:30.580265 :CAC7AA01:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:30.581066 :CAC7AA06:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:30.589906 :CAC7AA09:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:30.574 2025-09-08 19:42:30.589908 :CAC7AA0A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20876 pso_num=28 pso_serial#=169 2025-09-08 19:42:30.590067 :CAC7AA13:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20876 (legacy spawn) 2025-09-08 19:42:30.596636 :CAC7AA19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:30.596806 :CAC7AA22:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:30.597593 :CAC7AA23:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:33.791070 :CAC7AB06:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:33.778 2025-09-08 19:42:33.791072 :CAC7AB07:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22712 pso_num=28 pso_serial#=170 2025-09-08 19:42:33.791221 :CAC7AB08:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22712 (legacy spawn) 2025-09-08 19:42:33.798107 :CAC7AB09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:33.798281 :CAC7AB0E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:33.799110 :CAC7AB13:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:33.817682 :CAC7AB16:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:33.809 2025-09-08 19:42:33.817684 :CAC7AB17:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22408 pso_num=28 pso_serial#=171 2025-09-08 19:42:33.817832 :CAC7AB18:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22408 (legacy spawn) 2025-09-08 19:42:33.824763 :CAC7AB19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:33.824963 :CAC7AB22:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:33.825937 :CAC7AB23:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 19:42:49.184285 :CAC7B01C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 19:42:49.176 2025-09-08 19:42:49.184287 :CAC7B01D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18624 pso_num=28 pso_serial#=172 2025-09-08 19:42:49.184431 :CAC7B01E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18624 (legacy spawn) 2025-09-08 19:42:49.191275 :CAC7B01F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 19:42:49.191535 :CAC7B024:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 19:42:49.192514 :CAC7B029:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 22:00:00.046719 :CAD27937:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 22:00:00.038 2025-09-08 22:00:00.046721 :CAD27938:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19396 pso_num=28 pso_serial#=173 2025-09-08 22:00:00.046854 :CAD27939:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=19396 osp_idx=26 osp_ver=306731 osp_pg=0 (spawn #572493) 2025-09-08 22:00:00.046855 :CAD2793A:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:00:00.046855 :CAD2793B:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=7 time=1754451346 2025-09-08 22:00:00.046856 :CAD2793C:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:00:00.046856 :CAD2793D:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:00:00.046857 :CAD2793E:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:00:00.046857 :CAD2793F:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:00:00.046857 :CAD27940:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:00:00.047400 :CAD27941:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.047446 :CAD27948:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:00.047527 :CAD2795B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.051431 :CAD2796A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.052624 :CAD27972:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.052647 :CAD27975:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.052688 :CAD27980:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:00.054449 :CAD2798B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.054477 :CAD2798E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.054518 :CAD27999:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:00.055071 :CAD279A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.057511 :CAD279AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.062761 :CAD279B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.068281 :CAD279BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.069648 :CAD279C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.072715 :CAD279CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.073956 :CAD279D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.077221 :CAD279DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.080688 :CAD279E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.081084 :CAD279F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.081130 :CAD279F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-08 22:00:00.081144 :CAD279F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-08 22:00:00.083104 :CAD279FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.083146 :CAD27A00:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-08 22:00:00.083157 :CAD27A01:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-08 22:00:00.085157 :CAD27A07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.085203 :CAD27A0A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-08 22:00:00.085215 :CAD27A0B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-08 22:00:00.085387 :CAD27A11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.085402 :CAD27A14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.085439 :CAD27A1F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:00.087100 :CAD27A2A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 22:00:00.691660 :CAD27A2D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 22:00:00.679 2025-09-08 22:00:00.691662 :CAD27A2E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22952 pso_num=28 pso_serial#=174 2025-09-08 22:00:00.691826 :CAD27A2F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22952 (legacy spawn) 2025-09-08 22:00:00.698274 :CAD27A30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.698456 :CAD27A35:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 22:00:00.699289 :CAD27A3A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 22:00:00.708065 :CAD27A3D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 22:00:00.694 2025-09-08 22:00:00.708067 :CAD27A3E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16340 pso_num=28 pso_serial#=175 2025-09-08 22:00:00.708213 :CAD27A3F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16340 (legacy spawn) 2025-09-08 22:00:00.714787 :CAD27A40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:00.714952 :CAD27A48:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-08 22:00:00.715763 :CAD27A4A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-08 22:00:02.043533 :CAD27AC3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-08 22:00:02.038 2025-09-08 22:00:02.043535 :CAD27AC4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12488 pso_num=28 pso_serial#=176 2025-09-08 22:00:02.043675 :CAD27AC5:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=12488 osp_idx=26 osp_ver=306734 osp_pg=0 (spawn #572494) 2025-09-08 22:00:02.043675 :CAD27AC6:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-08 22:00:02.043676 :CAD27AC7:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=8 time=1754453346 2025-09-08 22:00:02.043676 :CAD27AC8:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-08 22:00:02.043677 :CAD27AC9:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-08 22:00:02.043677 :CAD27ACA:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-08 22:00:02.043677 :CAD27ACB:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-08 22:00:02.043677 :CAD27ACC:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-08 22:00:02.044190 :CAD27ADA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.049463 :CAD27B07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.049527 :CAD27B0F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-09-08 22:00:02.059382 :CAD27B62:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-08 22:00:02.059976 :CAD27B63:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 31b3120000000000 2025-09-08 22:00:02.061595 :CAD27B79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.061674 :CAD27B8D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.061683 :CAD27B94:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-09-08 22:00:02.061687 :CAD27B95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.061715 :CAD27BA1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.062357 :CAD27BAC:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 236926629) 2025-09-08 22:00:02.062361 :CAD27BAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.062382 :CAD27BB9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.062389 :CAD27BC5:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65564 1) 2025-09-08 22:00:02.062392 :CAD27BC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.062417 :CAD27BD1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.062421 :CAD27BD4:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65564) 2025-09-08 22:00:02.062423 :CAD27BDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.062470 :CAD27C0C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.063464 :CAD27CA2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.063488 :CAD27CA6:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-09-08 22:00:02.064403 :CAD27CB6:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 32b3120000000000 2025-09-08 22:00:02.064771 :CAD27CB7:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 236926629) 2025-09-08 22:00:02.064776 :CAD27CB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.064797 :CAD27CC3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:02.073694 :CAD27D3A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-09-08 22:00:02.074232 :CAD27D58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.075117 :CAD27D8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.075683 :CAD27DA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.075721 :CAD27DAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-08 22:00:02.163322 :CAD27E0D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-08 22:00:04.020476 :CAD28258:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 28 (osid: 12488, J002)