Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250724222159\orcl_j000_13376_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:36104M/63366M, Ph+PgF:41914M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 13376, image: ORACLE.EXE (J000) *** 2025-07-24 22:22:00.063 *** SESSION ID:(177.8881) 2025-07-24 22:22:00.063 *** 2025-07-24 22:22:00.063 Process diagnostic dump for ORACLE.EXE (J000), OS id=13376, pid: 22, proc_ser: 236, sid: 177, sess_ser: 8881 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13376 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.273170 sec, exc=0.273170 sec, total=0.273170 sec wait times: max=0.500000 sec, heur=15.290897 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 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=27 seq_num=28 snap_id=1 wait times: snap=0.515204 sec, exc=0.515204 sec, total=0.515204 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 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=25 seq_num=26 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=0.502087 sec, exc=0.502087 sec, total=0.502087 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 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.000010 sec of elapsed time Sampled Session History of session 177 serial 8881 --------------------------------------------------- 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 --------------------------------------------------- [16 samples, 22:21:44 - 22:22:00] idle wait at each sample [session created at: 22:21:44] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-24 22:22:00.063 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 13376, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-24 22:19:18.898900 :B6829E2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:19:18.907579 :B6829E2F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:19:18.894 2025-07-24 22:19:18.907581 :B6829E30:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15772 pso_num=22 pso_serial#=217 2025-07-24 22:19:18.907711 :B6829E31:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15772 (legacy spawn) 2025-07-24 22:19:18.914188 :B6829E32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:19:18.914350 :B6829E3B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:19:18.915140 :B6829E3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:19:23.356052 :B6829F40:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:19:23.347 2025-07-24 22:19:23.356054 :B6829F41:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9844 pso_num=22 pso_serial#=218 2025-07-24 22:19:23.356192 :B6829F42:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9844 (legacy spawn) 2025-07-24 22:19:23.362609 :B6829F43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:19:23.362781 :B6829F48:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:19:23.363580 :B6829F4D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:19:27.165312 :B682A09C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:19:27.160 2025-07-24 22:19:27.165314 :B682A09D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18468 pso_num=22 pso_serial#=219 2025-07-24 22:19:27.165449 :B682A09E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18468 osp_idx=0 osp_ver=444756 osp_pg=0 (spawn #419201) 2025-07-24 22:19:27.165450 :B682A09F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:19:27.165451 :B682A0A0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=116 time=2076343064 2025-07-24 22:19:27.165451 :B682A0A1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:19:27.165451 :B682A0A2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:19:27.165451 :B682A0A3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:19:27.165452 :B682A0A4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:19:27.165452 :B682A0A5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:19:27.165970 :B682A0A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:19:27.166021 :B682A0AC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-07-24 22:19:27.170152 :B682A0BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-24 22:19:27.170193 :B682A0BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-24 22:19:27.170454 :B682A0BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-24 22:19:27.170470 :B682A0BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-24 22:19:27.170685 :B682A0BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-24 22:19:27.170699 :B682A0C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-24 22:19:27.170910 :B682A0C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-24 22:19:27.170924 :B682A0C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-24 22:19:27.171131 :B682A0C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-24 22:19:27.171144 :B682A0C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-24 22:19:27.171351 :B682A0C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-24 22:19:27.171364 :B682A0C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-24 22:19:27.173808 :B682A0C7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:19:44.542803 :B682A60C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:19:44.537 2025-07-24 22:19:44.542805 :B682A60D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18624 pso_num=22 pso_serial#=220 2025-07-24 22:19:44.542951 :B682A60E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18624 osp_idx=0 osp_ver=444757 osp_pg=0 (spawn #419202) 2025-07-24 22:19:44.542951 :B682A60F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:19:44.542952 :B682A610:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=117 time=2076360439 2025-07-24 22:19:44.542952 :B682A611:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:19:44.542952 :B682A612:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:19:44.542953 :B682A613:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:19:44.542953 :B682A614:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:19:44.542953 :B682A615:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:19:44.543439 :B682A616:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:19:44.544135 :B682A62B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:19:44.551674 :B682A647:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-24 22:19:44.552043 :B682A648:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:19:44.552080 :B682A64B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-24 22:19:44.553099 :B682A651:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:19:44.553230 :B682A65A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:14.654154 :B682AFF7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:24.056883 :B682B2C4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:24.044 2025-07-24 22:20:24.056885 :B682B2C5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16220 pso_num=22 pso_serial#=221 2025-07-24 22:20:24.057023 :B682B2C6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16220 (legacy spawn) 2025-07-24 22:20:24.063502 :B682B2C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:24.063682 :B682B2D0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:24.064519 :B682B2D1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:25.505237 :B682B3A4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:25.497 2025-07-24 22:20:25.505239 :B682B3A5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18796 pso_num=22 pso_serial#=222 2025-07-24 22:20:25.505382 :B682B3A6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18796 (legacy spawn) 2025-07-24 22:20:25.511867 :B682B3A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:25.512054 :B682B3AC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:25.512911 :B682B3B1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:28.056579 :B682B48E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:28.042 2025-07-24 22:20:28.056580 :B682B48F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16688 pso_num=22 pso_serial#=223 2025-07-24 22:20:28.056714 :B682B490:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16688 (legacy spawn) 2025-07-24 22:20:28.075139 :B682B491:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:28.075307 :B682B496:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:28.076143 :B682B49B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:28.084768 :B682B49E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:28.074 2025-07-24 22:20:28.084770 :B682B49F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18356 pso_num=22 pso_serial#=224 2025-07-24 22:20:28.084910 :B682B4A0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18356 (legacy spawn) 2025-07-24 22:20:28.091489 :B682B4A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:28.091662 :B682B4AA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:28.092451 :B682B4AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:31.225536 :B682B59C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:31.212 2025-07-24 22:20:31.225538 :B682B59D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18004 pso_num=22 pso_serial#=225 2025-07-24 22:20:31.225677 :B682B59E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18004 (legacy spawn) 2025-07-24 22:20:31.232182 :B682B59F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:31.232353 :B682B5A8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:31.233204 :B682B5A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:31.241950 :B682B5AC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:31.228 2025-07-24 22:20:31.241952 :B682B5AD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19284 pso_num=22 pso_serial#=226 2025-07-24 22:20:31.242093 :B682B5AE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19284 (legacy spawn) 2025-07-24 22:20:31.248578 :B682B5AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:31.248749 :B682B5B8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:31.249548 :B682B5B9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:44.003290 :B682B976:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:43.990 2025-07-24 22:20:44.003292 :B682B977:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14268 pso_num=22 pso_serial#=227 2025-07-24 22:20:44.003431 :B682B978:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14268 (legacy spawn) 2025-07-24 22:20:44.022831 :B682B979:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:44.023008 :B682B97E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:44.023857 :B682B983:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:44.032643 :B682B986:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:44.021 2025-07-24 22:20:44.032645 :B682B987:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18724 pso_num=22 pso_serial#=228 2025-07-24 22:20:44.032785 :B682B988:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18724 (legacy spawn) 2025-07-24 22:20:44.039295 :B682B989:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:44.039476 :B682B991:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:20:44.040279 :B682B993:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:20:44.652091 :B682B9BA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:20:44.646 2025-07-24 22:20:44.652093 :B682B9BB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10356 pso_num=22 pso_serial#=229 2025-07-24 22:20:44.652233 :B682B9BC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10356 osp_idx=0 osp_ver=444766 osp_pg=0 (spawn #419204) 2025-07-24 22:20:44.652233 :B682B9BD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:20:44.652234 :B682B9BE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=118 time=2076420548 2025-07-24 22:20:44.652234 :B682B9BF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:20:44.652235 :B682B9C0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:20:44.652235 :B682B9C1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:20:44.652235 :B682B9C2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:20:44.652236 :B682B9C3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:20:44.652733 :B682B9C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:20:44.653388 :B682B9D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:44.664393 :B682B9F5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-24 22:20:44.664869 :B682B9F6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-24 22:20:44.667186 :B682B9F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-24 22:20:44.667575 :B682B9F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:44.667613 :B682B9FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-24 22:20:44.668506 :B682BA01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:20:44.668630 :B682BA0A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:14.766486 :B682C3D9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:27.732079 :B682C83D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:27.723 2025-07-24 22:21:27.732082 :B682C83E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12620 pso_num=22 pso_serial#=230 2025-07-24 22:21:27.732231 :B682C83F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12620 (legacy spawn) 2025-07-24 22:21:27.738653 :B682C840:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:27.738825 :B682C845:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:27.739652 :B682C84A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:32.335478 :B682C951:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:32.326 2025-07-24 22:21:32.335480 :B682C952:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14064 pso_num=22 pso_serial#=231 2025-07-24 22:21:32.335617 :B682C953:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14064 (legacy spawn) 2025-07-24 22:21:32.342134 :B682C954:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:32.342300 :B682C959:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:32.343126 :B682C95E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:40.698970 :B682CC24:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:40.684 2025-07-24 22:21:40.698971 :B682CC25:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18756 pso_num=22 pso_serial#=232 2025-07-24 22:21:40.699112 :B682CC26:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18756 (legacy spawn) 2025-07-24 22:21:40.705546 :B682CC27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:40.705725 :B682CC2C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:40.706516 :B682CC31:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:40.715231 :B682CC34:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:40.700 2025-07-24 22:21:40.715232 :B682CC35:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14460 pso_num=22 pso_serial#=233 2025-07-24 22:21:40.715370 :B682CC36:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14460 (legacy spawn) 2025-07-24 22:21:40.721849 :B682CC37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:40.722017 :B682CC40:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:40.722824 :B682CC41:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:44.009817 :B682CD28:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:43.997 2025-07-24 22:21:44.009819 :B682CD29:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17648 pso_num=22 pso_serial#=234 2025-07-24 22:21:44.009958 :B682CD2A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17648 (legacy spawn) 2025-07-24 22:21:44.016552 :B682CD2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:44.016735 :B682CD34:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:44.017550 :B682CD35:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:44.026157 :B682CD38:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:44.013 2025-07-24 22:21:44.026159 :B682CD39:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19152 pso_num=22 pso_serial#=235 2025-07-24 22:21:44.026297 :B682CD3A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19152 (legacy spawn) 2025-07-24 22:21:44.032816 :B682CD3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:44.032975 :B682CD43:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-24 22:21:44.033821 :B682CD45:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-24 22:21:44.768382 :B682CD6C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-24 22:21:44.763 2025-07-24 22:21:44.768384 :B682CD6D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13376 pso_num=22 pso_serial#=236 2025-07-24 22:21:44.768525 :B682CD6E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13376 osp_idx=0 osp_ver=444773 osp_pg=0 (spawn #419206) 2025-07-24 22:21:44.768526 :B682CD6F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-24 22:21:44.768526 :B682CD70:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=119 time=2076480658 2025-07-24 22:21:44.768526 :B682CD71:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:21:44.768527 :B682CD72:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:21:44.768527 :B682CD73:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:21:44.768528 :B682CD74:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:21:44.768528 :B682CD75:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:21:44.769030 :B682CD76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-24 22:21:44.769703 :B682CD8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:44.777301 :B682CDA7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-24 22:21:44.777664 :B682CDA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:44.777701 :B682CDAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-24 22:21:44.778626 :B682CDB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-24 22:21:44.778828 :B682CDB9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 13376, J000)