Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607222200\orcl_j000_15440_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:36828M/63366M, Ph+PgF:43447M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15440, image: ORACLE.EXE (J000) *** 2025-06-07 22:22:00.375 *** SESSION ID:(177.2611) 2025-06-07 22:22:00.375 *** 2025-06-07 22:22:00.375 Process diagnostic dump for ORACLE.EXE (J000), OS id=15440, pid: 22, proc_ser: 46, sid: 177, sess_ser: 2611 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15440 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.352460 sec, exc=0.352460 sec, total=0.352460 sec wait times: max=0.500000 sec, heur=18.401658 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 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.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.510113 sec, exc=0.510113 sec, total=0.510113 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 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=29 seq_num=30 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.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 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.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.504588 sec, exc=0.504588 sec, total=0.504588 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 177 serial 2611 --------------------------------------------------- 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 --------------------------------------------------- [19 samples, 22:21:41 - 22:22:00] idle wait at each sample [session created at: 22:21:41] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 22:22:00.375 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15440, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 22:18:26.607320 :A1B08687:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16040 (legacy spawn) 2025-06-07 22:18:26.613740 :A1B08688: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-06-07 22:18:26.613908 :A1B0868D: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-06-07 22:18:26.614699 :A1B08692:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:18:40.183888 :A1B08AA6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:18:40.175 2025-06-07 22:18:40.183890 :A1B08AA7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9804 pso_num=22 pso_serial#=26 2025-06-07 22:18:40.184044 :A1B08AA8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9804 (legacy spawn) 2025-06-07 22:18:40.190439 :A1B08ABC: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-06-07 22:18:40.190600 :A1B08AC5: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-06-07 22:18:40.191424 :A1B08AC6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:18:41.731764 :A1B08B4E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:18:41.726 2025-06-07 22:18:41.731765 :A1B08B4F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15760 pso_num=22 pso_serial#=27 2025-06-07 22:18:41.731907 :A1B08B50:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15760 osp_idx=0 osp_ver=275754 osp_pg=0 (spawn #262516) 2025-06-07 22:18:41.731907 :A1B08B51:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 22:18:41.731908 :A1B08B52:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=2310480375 2025-06-07 22:18:41.731908 :A1B08B53:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 22:18:41.731908 :A1B08B54:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 22:18:41.731909 :A1B08B55:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 22:18:41.731909 :A1B08B56:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 22:18:41.731909 :A1B08B57:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 22:18:41.732400 :A1B08B58: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-06-07 22:18:41.733061 :A1B08B6D: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-06-07 22:18:41.744049 :A1B08B89:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 22:18:41.744541 :A1B08B8A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 22:18:41.746837 :A1B08B8B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-07 22:18:41.747195 :A1B08B8C: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-06-07 22:18:41.747246 :A1B08B8F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-07 22:18:41.748164 :A1B08B95: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-06-07 22:18:41.748297 :A1B08B9E: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-06-07 22:19:11.830251 :A1B094FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:14.483980 :A1B095DC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:14.474 2025-06-07 22:19:14.483982 :A1B095DD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12292 pso_num=22 pso_serial#=28 2025-06-07 22:19:14.484119 :A1B095DE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12292 (legacy spawn) 2025-06-07 22:19:14.490583 :A1B095DF: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-06-07 22:19:14.490747 :A1B095E4: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-06-07 22:19:14.491532 :A1B095E9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:20.035460 :A1B09776:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:20.021 2025-06-07 22:19:20.035462 :A1B09777:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15304 pso_num=22 pso_serial#=29 2025-06-07 22:19:20.035595 :A1B09778:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15304 (legacy spawn) 2025-06-07 22:19:20.042057 :A1B09779: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-06-07 22:19:20.042224 :A1B0977E: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-06-07 22:19:20.043008 :A1B09783:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:20.051569 :A1B09786:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:20.037 2025-06-07 22:19:20.051571 :A1B09787:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14556 pso_num=22 pso_serial#=30 2025-06-07 22:19:20.051704 :A1B09788:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14556 (legacy spawn) 2025-06-07 22:19:20.058166 :A1B09789: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-06-07 22:19:20.058346 :A1B09792: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-06-07 22:19:20.059221 :A1B09793:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:23.252957 :A1B09883:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:23.240 2025-06-07 22:19:23.252959 :A1B09884:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14424 pso_num=22 pso_serial#=31 2025-06-07 22:19:23.253090 :A1B09885:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14424 (legacy spawn) 2025-06-07 22:19:23.259559 :A1B09886: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-06-07 22:19:23.259745 :A1B0988C: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-06-07 22:19:23.260535 :A1B09890:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:23.269107 :A1B09893:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:23.256 2025-06-07 22:19:23.269109 :A1B09894:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14048 pso_num=22 pso_serial#=32 2025-06-07 22:19:23.269239 :A1B09895:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14048 (legacy spawn) 2025-06-07 22:19:23.275650 :A1B09896: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-06-07 22:19:23.275809 :A1B0989F: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-06-07 22:19:23.276617 :A1B098A0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:28.798121 :A1B09A69:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:28.790 2025-06-07 22:19:28.798123 :A1B09A6A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12356 pso_num=22 pso_serial#=33 2025-06-07 22:19:28.798261 :A1B09A6B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12356 (legacy spawn) 2025-06-07 22:19:28.804846 :A1B09A6C: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-06-07 22:19:28.805018 :A1B09A71: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-06-07 22:19:28.805804 :A1B09A76:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:19:41.805140 :A1B09E9B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:19:41.799 2025-06-07 22:19:41.805142 :A1B09E9C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15148 pso_num=22 pso_serial#=34 2025-06-07 22:19:41.805287 :A1B09E9D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15148 osp_idx=0 osp_ver=275761 osp_pg=0 (spawn #262518) 2025-06-07 22:19:41.805288 :A1B09E9E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 22:19:41.805289 :A1B09E9F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=2310540454 2025-06-07 22:19:41.805289 :A1B09EA0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 22:19:41.805289 :A1B09EA1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 22:19:41.805289 :A1B09EA2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 22:19:41.805290 :A1B09EA3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 22:19:41.805290 :A1B09EA4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 22:19:41.805780 :A1B09EA5: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-06-07 22:19:41.806453 :A1B09EBA: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-06-07 22:19:41.814010 :A1B09ED6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-07 22:19:41.814367 :A1B09ED7: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-06-07 22:19:41.814402 :A1B09EDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-07 22:19:41.815279 :A1B09EE0: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-06-07 22:19:41.815393 :A1B09EE9: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-06-07 22:20:11.896132 :A1B0A908:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:22.620089 :A1B0AC4B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:22.609 2025-06-07 22:20:22.620091 :A1B0AC4C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13424 pso_num=22 pso_serial#=35 2025-06-07 22:20:22.620228 :A1B0AC4D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13424 (legacy spawn) 2025-06-07 22:20:22.626769 :A1B0AC4E: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-06-07 22:20:22.626940 :A1B0AC57: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-06-07 22:20:22.627752 :A1B0AC58:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:30.964272 :A1B0AEE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:30.956 2025-06-07 22:20:30.964274 :A1B0AEE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13096 pso_num=22 pso_serial#=36 2025-06-07 22:20:30.964411 :A1B0AEE2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13096 (legacy spawn) 2025-06-07 22:20:30.970929 :A1B0AEE3: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-06-07 22:20:30.971103 :A1B0AEE8: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-06-07 22:20:30.971989 :A1B0AEED:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:33.142336 :A1B0AF85:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:33.128 2025-06-07 22:20:33.142338 :A1B0AF86:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1164 pso_num=22 pso_serial#=37 2025-06-07 22:20:33.142478 :A1B0AF87:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1164 (legacy spawn) 2025-06-07 22:20:33.148937 :A1B0AF88: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-06-07 22:20:33.149112 :A1B0AF8D: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-06-07 22:20:33.149904 :A1B0AF92:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:33.167751 :A1B0AF95:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:33.159 2025-06-07 22:20:33.167754 :A1B0AF96:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10360 pso_num=22 pso_serial#=38 2025-06-07 22:20:33.167893 :A1B0AF97:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10360 (legacy spawn) 2025-06-07 22:20:33.174370 :A1B0AF98: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-06-07 22:20:33.174533 :A1B0AFA1: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-06-07 22:20:33.175360 :A1B0AFA2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:36.440162 :A1B0B0CD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:36.427 2025-06-07 22:20:36.440164 :A1B0B0CE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12576 pso_num=22 pso_serial#=39 2025-06-07 22:20:36.440302 :A1B0B0CF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12576 (legacy spawn) 2025-06-07 22:20:36.446815 :A1B0B0D0: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-06-07 22:20:36.447000 :A1B0B0D5: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-06-07 22:20:36.447813 :A1B0B0DA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:36.456364 :A1B0B0DD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:36.443 2025-06-07 22:20:36.456366 :A1B0B0DE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14056 pso_num=22 pso_serial#=40 2025-06-07 22:20:36.456499 :A1B0B0DF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14056 (legacy spawn) 2025-06-07 22:20:36.462972 :A1B0B0E0: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-06-07 22:20:36.463138 :A1B0B0E8: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-06-07 22:20:36.463948 :A1B0B0EA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:20:41.891817 :A1B0B273:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:20:41.885 2025-06-07 22:20:41.891819 :A1B0B274:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12152 pso_num=22 pso_serial#=41 2025-06-07 22:20:41.891978 :A1B0B275:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12152 osp_idx=0 osp_ver=275768 osp_pg=0 (spawn #262520) 2025-06-07 22:20:41.891979 :A1B0B276:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 22:20:41.891979 :A1B0B277:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=2310600532 2025-06-07 22:20:41.891979 :A1B0B278:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 22:20:41.891980 :A1B0B279:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 22:20:41.891980 :A1B0B27A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 22:20:41.891980 :A1B0B27B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 22:20:41.891981 :A1B0B27C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 22:20:41.892514 :A1B0B27D: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-06-07 22:20:41.893218 :A1B0B292: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-06-07 22:20:41.904352 :A1B0B2AF:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 22:20:41.904818 :A1B0B2B0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 22:20:41.907219 :A1B0B2B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-07 22:20:41.907585 :A1B0B2B2: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-06-07 22:20:41.907622 :A1B0B2B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-07 22:20:41.908515 :A1B0B2BB: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-06-07 22:20:41.908635 :A1B0B2C4: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-06-07 22:21:11.969367 :A1B0BC76:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:21:13.153894 :A1B0BCEA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:21:13.140 2025-06-07 22:21:13.153896 :A1B0BCEB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14164 pso_num=22 pso_serial#=42 2025-06-07 22:21:13.154032 :A1B0BCEC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14164 (legacy spawn) 2025-06-07 22:21:13.160444 :A1B0BCED: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-06-07 22:21:13.160630 :A1B0BCF2: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-06-07 22:21:13.161440 :A1B0BCF7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:21:13.170021 :A1B0BCFA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:21:13.156 2025-06-07 22:21:13.170023 :A1B0BCFB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8784 pso_num=22 pso_serial#=43 2025-06-07 22:21:13.170158 :A1B0BCFC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8784 (legacy spawn) 2025-06-07 22:21:13.176587 :A1B0BCFD: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-06-07 22:21:13.176763 :A1B0BD06: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-06-07 22:21:13.177619 :A1B0BD07:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:21:30.783771 :A1B0C269:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:21:30.775 2025-06-07 22:21:30.783773 :A1B0C26A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13000 pso_num=22 pso_serial#=44 2025-06-07 22:21:30.783912 :A1B0C26B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13000 (legacy spawn) 2025-06-07 22:21:30.790432 :A1B0C26C: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-06-07 22:21:30.790604 :A1B0C271: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-06-07 22:21:30.791435 :A1B0C276:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:21:33.158427 :A1B0C30B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:21:33.150 2025-06-07 22:21:33.158429 :A1B0C30C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15996 pso_num=22 pso_serial#=45 2025-06-07 22:21:33.158574 :A1B0C30D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15996 (legacy spawn) 2025-06-07 22:21:33.165062 :A1B0C30E: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-06-07 22:21:33.165234 :A1B0C313: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-06-07 22:21:33.166062 :A1B0C318:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-07 22:21:41.970001 :A1B0C5D9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-07 22:21:41.965 2025-06-07 22:21:41.970003 :A1B0C5DA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15440 pso_num=22 pso_serial#=46 2025-06-07 22:21:41.970140 :A1B0C5DB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15440 osp_idx=0 osp_ver=275773 osp_pg=0 (spawn #262522) 2025-06-07 22:21:41.970141 :A1B0C5DC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 22:21:41.970141 :A1B0C5DD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=13 time=2310660610 2025-06-07 22:21:41.970142 :A1B0C5DE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 22:21:41.970142 :A1B0C5DF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 22:21:41.970142 :A1B0C5E0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 22:21:41.970143 :A1B0C5E1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 22:21:41.970143 :A1B0C5E2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 22:21:41.970632 :A1B0C5E3: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-06-07 22:21:41.971318 :A1B0C5F8: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-06-07 22:21:41.978859 :A1B0C614:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-07 22:21:41.979226 :A1B0C615: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-06-07 22:21:41.979260 :A1B0C618:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-07 22:21:41.980117 :A1B0C61E: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-06-07 22:21:41.980236 :A1B0C627: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: 15440, J000)