Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250505220006\orcl_j004_7236_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:37483M/63366M, Ph+PgF:44967M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 7236, image: ORACLE.EXE (J004) *** 2025-05-05 22:00:06.731 *** SESSION ID:(241.1365) 2025-05-05 22:00:06.731 *** 2025-05-05 22:00:06.731 Process diagnostic dump for ORACLE.EXE (J004), OS id=7236, pid: 30, proc_ser: 159, sid: 241, sess_ser: 1365 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 7236 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.296823 sec, exc=0.296823 sec, total=0.296823 sec wait times: max=0.500000 sec, heur=2.804806 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.000012 sec since current wait 0: 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.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 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=1 seq_num=2 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.507932 sec, exc=0.507932 sec, total=0.507932 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 241 serial 1365 --------------------------------------------------- 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, 22:00:03 - 22:00:06] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.015000 sec (max dump time=30.000000 sec) *** 2025-05-05 22:00:06.746 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 7236, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 22:02:41.191350 :92BEE3B0:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=10076 osp_idx=28 osp_ver=661 osp_pg=0 (spawn #148750) 2025-05-04 22:02:41.191351 :92BEE3B1:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 22:02:41.191351 :92BEE3B2:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=57 time=3666938359 2025-05-04 22:02:41.191352 :92BEE3B3:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 22:02:41.191352 :92BEE3B4:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 22:02:41.191352 :92BEE3B5:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 22:02:41.191352 :92BEE3B6:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 22:02:41.191353 :92BEE3B7:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 22:02:41.191861 :92BEE3B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 22:02:41.191907 :92BEE3BF:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-04 22:02:41.198402 :92BEE3CC:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-04 23:02:43.326063 :92C3A97E:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-04 23:02:43.323 2025-05-04 23:02:43.326064 :92C3A97F:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=6532 pso_num=30 pso_serial#=153 2025-05-04 23:02:43.326192 :92C3A980:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=6532 osp_idx=28 osp_ver=662 osp_pg=0 (spawn #148891) 2025-05-04 23:02:43.326193 :92C3A981:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 23:02:43.326193 :92C3A982:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=58 time=3670540484 2025-05-04 23:02:43.326194 :92C3A983:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 23:02:43.326194 :92C3A984:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 23:02:43.326195 :92C3A985:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 23:02:43.326195 :92C3A986:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 23:02:43.326195 :92C3A987:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 23:02:43.326864 :92C3A988:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 23:02:43.326912 :92C3A98E:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-04 23:02:43.335644 :92C3A9A6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-04 23:59:13.817918 :92C81D04:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-04 23:59:13.804 2025-05-04 23:59:13.817920 :92C81D05:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11320 pso_num=30 pso_serial#=154 2025-05-04 23:59:13.818058 :92C81D06:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11320 (legacy spawn) 2025-05-04 23:59:13.831891 :92C81D07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 23:59:47.068542 :92C829AA:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-04 23:59:50.575533 :92C82B0B:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-04 23:59:50.575 2025-05-04 23:59:50.575535 :92C82B0C:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10916 pso_num=30 pso_serial#=155 2025-05-04 23:59:50.575689 :92C82B0D:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=10916 (legacy spawn) 2025-05-04 23:59:50.589479 :92C82B0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:23.840409 :92C836F1:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-05 00:00:52.203121 :92C843DA:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-05 00:00:52.197 2025-05-05 00:00:52.203123 :92C843DB:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11672 pso_num=30 pso_serial#=156 2025-05-05 00:00:52.203256 :92C843DC:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11672 osp_idx=28 osp_ver=665 osp_pg=0 (spawn #149024) 2025-05-05 00:00:52.203257 :92C843DD:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 00:00:52.203258 :92C843DE:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=59 time=3674029359 2025-05-05 00:00:52.203258 :92C843DF:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 00:00:52.203259 :92C843E0:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 00:00:52.203259 :92C843E1:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 00:00:52.203260 :92C843E2:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 00:00:52.203262 :92C843E3:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 00:00:52.203741 :92C843E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.203783 :92C843EA:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-05 00:00:52.211492 :92C843F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.212678 :92C84400:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.213250 :92C84408:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.213725 :92C84410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.214163 :92C84418:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.216727 :92C84420:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.217850 :92C84428:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.266946 :92C84430:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.267837 :92C84438:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.268081 :92C84440:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.268292 :92C84448:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:52.269984 :92C84450:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-05 00:02:51.119645 :92C86BB6:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-05 00:02:51.107 2025-05-05 00:02:51.119648 :92C86BB7:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=6084 pso_num=30 pso_serial#=157 2025-05-05 00:02:51.119781 :92C86BB8:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=6084 osp_idx=28 osp_ver=666 osp_pg=0 (spawn #149030) 2025-05-05 00:02:51.119782 :92C86BB9:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 00:02:51.119782 :92C86BBA:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=60 time=3674148281 2025-05-05 00:02:51.119783 :92C86BBB:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 00:02:51.119783 :92C86BBC:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 00:02:51.119783 :92C86BBD:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 00:02:51.119783 :92C86BBE:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 00:02:51.119784 :92C86BBF:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 00:02:51.120285 :92C86BC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 00:02:51.120325 :92C86BC6:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-05 00:02:51.124474 :92C86BDE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-05 00:02:51.124525 :92C86BDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-05 00:02:51.124775 :92C86BE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-05 00:02:51.124791 :92C86BE1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-05 00:02:51.125088 :92C86BE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-05 00:02:51.125111 :92C86BE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-05 00:02:51.125336 :92C86BE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-05 00:02:51.125349 :92C86BE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-05 00:02:51.125558 :92C86BE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-05 00:02:51.125571 :92C86BE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-05 00:02:51.125792 :92C86BE8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:kelt.c:517 2025-05-05 00:02:51.125806 :92C86BE9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:kelt.c:517 2025-05-05 00:02:51.128143 :92C86C0C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-05 01:02:58.899118 :92CD375C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-05 01:02:58.896 2025-05-05 01:02:58.899119 :92CD375D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10872 pso_num=30 pso_serial#=158 2025-05-05 01:02:58.899250 :92CD375E:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=10872 osp_idx=28 osp_ver=667 osp_pg=0 (spawn #149173) 2025-05-05 01:02:58.899251 :92CD375F:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 01:02:58.899252 :92CD3760:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=61 time=3677756046 2025-05-05 01:02:58.899252 :92CD3761:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 01:02:58.899253 :92CD3762:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 01:02:58.899253 :92CD3763:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 01:02:58.899253 :92CD3764:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 01:02:58.899254 :92CD3765:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 01:02:58.899783 :92CD3766:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 01:02:58.899841 :92CD376C:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-05-05 01:02:58.908695 :92CD3793:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-05-05 22:00:02.055183 :9330F0EB:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 05-05 22:00:02.054 2025-05-05 22:00:02.055184 :9330F0EC:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=7236 pso_num=30 pso_serial#=159 2025-05-05 22:00:02.055306 :9330F0F0:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=7236 osp_idx=28 osp_ver=668 osp_pg=0 (spawn #152124) 2025-05-05 22:00:02.055307 :9330F0F1:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 22:00:02.055308 :9330F0F2:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=62 time=3753179203 2025-05-05 22:00:02.055308 :9330F0F3:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 22:00:02.055309 :9330F0F4:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 22:00:02.055309 :9330F0F5:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 22:00:02.055309 :9330F0F6:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 22:00:02.055310 :9330F0F7:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 22:00:02.055921 :9330F0FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 22:00:02.060804 :9330F11C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:02.060832 :9330F11F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-05-05 22:00:02.066157 :9330F27E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:02.284090 :9330F3DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:02.470268 :9330F401:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:02.602313 :9330F419:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.731589 :9330F62C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.732576 :9330F634:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.736558 :9330F63C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.738933 :9330F644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.741190 :9330F64C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.743475 :9330F654:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.745034 :9330F65C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.765552 :9330F66C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.766313 :9330F674:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.766885 :9330F67C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.767405 :9330F684:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.770026 :9330F68C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.771026 :9330F694:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.771848 :9330F695:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.772632 :9330F696:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.772843*:9330F698:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003171F328 estart=1746453602 eid=16784746 ctx=0x000000079FF419C0 cwh=0x0000000031922AE0 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-05 22:00:03.772843*:9330F699:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003171F328 curCtx=0x000000003177F148 pubTabIdxCnt=0 2025-05-05 22:00:03.772843*:9330F69A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003171F328 2025-05-05 22:00:03.772843*:9330F69C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003171F328 2025-05-05 22:00:03.772843*:9330F69E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003192DC60 estart=1746453602 eid=16777306 ctx=0x0000000776DDAA80 cwh=0x000000003169BC10 cpu=15625 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-05 22:00:03.772843*:9330F69F:sql_mon_query:keswx.c@6118:keswxCurPushMonitoring(): disable monitoring for xsc=0x000000003192DC60 curCtx=0x0000000000000000 reason: quick check failed 2025-05-05 22:00:03.772843*:9330F6A0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003192DC60 2025-05-05 22:00:03.772843*:9330F6A2:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003171F328 curCtx=0x000000003177F148 xsc->flg4=65792 flags=1 2025-05-05 22:00:03.772843*:9330F6A3:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003171F328 2025-05-05 22:00:03.773096 :9330F6AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.773103 :9330F6AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.773714 :9330F6AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.774287 :9330F6AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.774608 :9330F6B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.775735 :9330F6B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.811306 :9330F71B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.811356 :9330F71E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.812317 :9330F724:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.813237 :9330F72C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.813706 :9330F72D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.833147 :9330F735:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.833207 :9330F738:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.834121 :9330F73E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.834982 :9330F746:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.835427 :9330F747:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.852939 :9330F74F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.852992 :9330F752:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.853917 :9330F758:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.866177 :9330F760:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.866771 :9330F761:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.886690 :9330F76B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.886733 :9330F76E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.887559 :9330F774:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.888514 :9330F77C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.889056 :9330F77D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.909764 :9330F785:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.909812 :9330F788:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.910649 :9330F78E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.911456 :9330F796:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-05-05 22:00:03.911892 :9330F797:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.931668 :9330F79F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.931736 :9330F7A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-05-05 22:00:03.932597 :9330F7A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.933040 :9330F7B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.933387 :9330F7B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.933559 :9330F7C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.933688 :9330F7C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.933830 :9330F7D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-05-05 22:00:03.937911 :9330F7D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.938000 :9330F7D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-05 22:00:03.938059 :9330F7DD:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 7236, J004)