Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706220216\orcl_j002_13332_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:36981M/63366M, Ph+PgF:43013M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 13332, image: ORACLE.EXE (J002) *** 2025-07-06 22:02:16.775 *** SESSION ID:(219.19259) 2025-07-06 22:02:16.775 *** 2025-07-06 22:02:16.775 Process diagnostic dump for ORACLE.EXE (J002), OS id=13332, pid: 27, proc_ser: 95, sid: 219, sess_ser: 19259 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13332 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.015701 sec, exc=0.015701 sec, total=0.015701 sec wait times: max=0.500000 sec, heur=7.519621 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.505083 sec, exc=0.505083 sec, total=0.505083 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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=8 seq_num=9 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=7 seq_num=8 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 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000010 sec of elapsed time Sampled Session History of session 219 serial 19259 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:02:09 - 22:02:16] idle wait at each sample [session created at: 22:02:09] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-06 22:02:16.791 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 13332, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-06 21:57:23.332050 :AE7B34C9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:57:26.409952 :AE7B35E4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:57:26.396 2025-07-06 21:57:26.409954 :AE7B35E5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17544 pso_num=27 pso_serial#=69 2025-07-06 21:57:26.410084 :AE7B35E6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17544 (legacy spawn) 2025-07-06 21:57:26.416573 :AE7B35E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:57:26.416749 :AE7B35F0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:57:26.417530 :AE7B35F1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:57:26.426388 :AE7B35F4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:57:26.412 2025-07-06 21:57:26.426390 :AE7B35F5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14132 pso_num=27 pso_serial#=70 2025-07-06 21:57:26.426523 :AE7B35F6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14132 (legacy spawn) 2025-07-06 21:57:26.433263 :AE7B35F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:57:26.433439 :AE7B3600:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:57:26.434248 :AE7B3601:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:57:27.263656 :AE7B3642:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:57:27.255 2025-07-06 21:57:27.263658 :AE7B3643:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3724 pso_num=27 pso_serial#=71 2025-07-06 21:57:27.263800 :AE7B3644:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3724 (legacy spawn) 2025-07-06 21:57:27.270383 :AE7B3645:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:57:27.270560 :AE7B364A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:57:27.271386 :AE7B364F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:57:27.436991 :AE7B365E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:57:27.427 2025-07-06 21:57:27.436993 :AE7B365F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15664 pso_num=27 pso_serial#=72 2025-07-06 21:57:27.437128 :AE7B3660:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15664 (legacy spawn) 2025-07-06 21:57:27.443714 :AE7B3661:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:57:27.443893 :AE7B3669:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:57:27.444789 :AE7B366B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:58:29.427371 :AE7B4BCB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:58:29.419 2025-07-06 21:58:29.427374 :AE7B4BCC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8508 pso_num=27 pso_serial#=73 2025-07-06 21:58:29.427519 :AE7B4BCD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8508 (legacy spawn) 2025-07-06 21:58:29.434189 :AE7B4BCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:58:29.434370 :AE7B4BD3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:58:29.435197 :AE7B4BD8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:58:33.307933 :AE7B4D10:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:58:33.298 2025-07-06 21:58:33.307936 :AE7B4D11:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9700 pso_num=27 pso_serial#=74 2025-07-06 21:58:33.308086 :AE7B4D12:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9700 (legacy spawn) 2025-07-06 21:58:33.315183 :AE7B4D13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:58:33.315361 :AE7B4D18:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:58:33.316278 :AE7B4D1D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:58:35.636585 :AE7B4DDE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:58:35.627 2025-07-06 21:58:35.636587 :AE7B4DDF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14984 pso_num=27 pso_serial#=75 2025-07-06 21:58:35.636733 :AE7B4DE0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14984 (legacy spawn) 2025-07-06 21:58:35.643338 :AE7B4DE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:58:35.643514 :AE7B4DE6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:58:35.644326 :AE7B4DEB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:58:36.000685 :AE7B4E2C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:58:35.986 2025-07-06 21:58:36.000686 :AE7B4E2D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=6856 pso_num=27 pso_serial#=76 2025-07-06 21:58:36.000825 :AE7B4E2E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=6856 (legacy spawn) 2025-07-06 21:58:36.007445 :AE7B4E2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:58:36.007609 :AE7B4E34:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:58:36.008421 :AE7B4E39:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:58:36.017271 :AE7B4E3C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:58:36.017 2025-07-06 21:58:36.017273 :AE7B4E3D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8520 pso_num=27 pso_serial#=77 2025-07-06 21:58:36.017411 :AE7B4E3E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8520 (legacy spawn) 2025-07-06 21:58:36.023900 :AE7B4E3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:58:36.024073 :AE7B4E48:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:58:36.024882 :AE7B4E49:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:59:09.731130 :AE7B5970:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:59:09.722 2025-07-06 21:59:09.731132 :AE7B5971:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15748 pso_num=27 pso_serial#=78 2025-07-06 21:59:09.731270 :AE7B5972:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15748 (legacy spawn) 2025-07-06 21:59:09.737988 :AE7B5973:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:59:09.738158 :AE7B5978:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:59:09.738976 :AE7B597D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:59:12.754877 :AE7B5A6E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:59:12.745 2025-07-06 21:59:12.754879 :AE7B5A6F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13152 pso_num=27 pso_serial#=79 2025-07-06 21:59:12.755023 :AE7B5A70:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13152 (legacy spawn) 2025-07-06 21:59:12.761640 :AE7B5A7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:59:12.761815 :AE7B5A81:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:59:12.762627 :AE7B5A86:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:59:12.771662 :AE7B5A89:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:59:12.760 2025-07-06 21:59:12.771664 :AE7B5A8A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17720 pso_num=27 pso_serial#=80 2025-07-06 21:59:12.771804 :AE7B5A8B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17720 (legacy spawn) 2025-07-06 21:59:12.778552 :AE7B5A8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:59:12.778719 :AE7B5A95:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:59:12.779546 :AE7B5A96:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:59:15.884638 :AE7B5B94:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:59:15.870 2025-07-06 21:59:15.884640 :AE7B5B95:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2768 pso_num=27 pso_serial#=81 2025-07-06 21:59:15.884815 :AE7B5B96:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2768 (legacy spawn) 2025-07-06 21:59:15.892356 :AE7B5B97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:59:15.892538 :AE7B5B9C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:59:15.893401 :AE7B5BA1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:59:15.902679 :AE7B5BA4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:59:15.901 2025-07-06 21:59:15.902681 :AE7B5BA5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14108 pso_num=27 pso_serial#=82 2025-07-06 21:59:15.902835 :AE7B5BA6:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14108 (legacy spawn) 2025-07-06 21:59:15.909944 :AE7B5BA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:59:15.910127 :AE7B5BB0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:59:15.910993 :AE7B5BB1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 21:59:31.781357 :AE7B609C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 21:59:31.773 2025-07-06 21:59:31.781360 :AE7B609D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=3236 pso_num=27 pso_serial#=83 2025-07-06 21:59:31.781499 :AE7B609E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=3236 (legacy spawn) 2025-07-06 21:59:31.806040 :AE7B609F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 21:59:31.806215 :AE7B60A8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 21:59:31.807044 :AE7B60A9:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:00:17.937110 :AE7B7163:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:00:17.928 2025-07-06 22:00:17.937112 :AE7B7164:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14784 pso_num=27 pso_serial#=84 2025-07-06 22:00:17.937280 :AE7B7165:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14784 (legacy spawn) 2025-07-06 22:00:17.943939 :AE7B7166:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:00:17.944118 :AE7B716D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:00:17.944948 :AE7B7170:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:00:25.302627 :AE7B73B5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:00:25.295 2025-07-06 22:00:25.302629 :AE7B73B6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14980 pso_num=27 pso_serial#=85 2025-07-06 22:00:25.302766 :AE7B73B7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14980 (legacy spawn) 2025-07-06 22:00:25.309415 :AE7B73B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:00:25.309591 :AE7B73BD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:00:25.310400 :AE7B73C2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:00:25.319222 :AE7B73C5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:00:25.311 2025-07-06 22:00:25.319224 :AE7B73C6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17736 pso_num=27 pso_serial#=86 2025-07-06 22:00:25.319362 :AE7B73C7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17736 (legacy spawn) 2025-07-06 22:00:25.326022 :AE7B73C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:00:25.326231 :AE7B73CE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:00:25.327101 :AE7B73D1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:00:28.593149 :AE7B74D9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:00:28.580 2025-07-06 22:00:28.593151 :AE7B74DA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13948 pso_num=27 pso_serial#=87 2025-07-06 22:00:28.593294 :AE7B74DB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13948 (legacy spawn) 2025-07-06 22:00:28.600056 :AE7B74DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:00:28.600253 :AE7B74E4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:00:28.600992 :AE7B74E6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:00:28.610261 :AE7B74E9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:00:28.596 2025-07-06 22:00:28.610262 :AE7B74EA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15628 pso_num=27 pso_serial#=88 2025-07-06 22:00:28.610379 :AE7B74EB:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15628 (legacy spawn) 2025-07-06 22:00:28.616900 :AE7B74EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:00:28.617073 :AE7B74F5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:00:28.617884 :AE7B74F6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:01:26.237736 :AE7B89BB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:01:26.228 2025-07-06 22:01:26.237738 :AE7B89BC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13932 pso_num=27 pso_serial#=89 2025-07-06 22:01:26.237875 :AE7B89BD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13932 (legacy spawn) 2025-07-06 22:01:26.244452 :AE7B89BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:01:26.244611 :AE7B89C7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:01:26.245393 :AE7B89C8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:01:34.314510 :AE7B8C81:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:01:34.301 2025-07-06 22:01:34.314512 :AE7B8C82:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10656 pso_num=27 pso_serial#=90 2025-07-06 22:01:34.314649 :AE7B8C83:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10656 (legacy spawn) 2025-07-06 22:01:34.321207 :AE7B8C84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:01:34.321375 :AE7B8C89:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:01:34.322158 :AE7B8C8E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:01:34.330887 :AE7B8C91:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:01:34.316 2025-07-06 22:01:34.330889 :AE7B8C92:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7280 pso_num=27 pso_serial#=91 2025-07-06 22:01:34.331019 :AE7B8C93:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7280 (legacy spawn) 2025-07-06 22:01:34.337551 :AE7B8C94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:01:34.337714 :AE7B8C9D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:01:34.338543 :AE7B8C9E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:01:36.139664 :AE7B8D32:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:01:36.131 2025-07-06 22:01:36.139666 :AE7B8D33:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17424 pso_num=27 pso_serial#=92 2025-07-06 22:01:36.139802 :AE7B8D34:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17424 (legacy spawn) 2025-07-06 22:01:36.164468 :AE7B8D35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:01:36.164648 :AE7B8D3A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:01:36.165542 :AE7B8D3F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:01:37.896365 :AE7B8DAA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:01:37.881 2025-07-06 22:01:37.896367 :AE7B8DAB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7572 pso_num=27 pso_serial#=93 2025-07-06 22:01:37.896516 :AE7B8DAC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7572 (legacy spawn) 2025-07-06 22:01:37.903668 :AE7B8DAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:01:37.903858 :AE7B8DB5:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:01:37.904763 :AE7B8DB7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:01:37.914006 :AE7B8DBA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:01:37.912 2025-07-06 22:01:37.914008 :AE7B8DBB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17568 pso_num=27 pso_serial#=94 2025-07-06 22:01:37.914150 :AE7B8DBC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17568 (legacy spawn) 2025-07-06 22:01:37.921040 :AE7B8DBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:01:37.921210 :AE7B8DC6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-06 22:01:37.922120 :AE7B8DC7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-06 22:02:09.007598 :AE7B9CC9:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-06 22:02:09.002 2025-07-06 22:02:09.007601 :AE7B9CCA:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13332 pso_num=27 pso_serial#=95 2025-07-06 22:02:09.007743 :AE7B9CCB:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13332 osp_idx=25 osp_ver=140608 osp_pg=0 (spawn #359181) 2025-07-06 22:02:09.007744 :AE7B9CCC:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 22:02:09.007744 :AE7B9CCD:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=148 time=520104908 2025-07-06 22:02:09.007745 :AE7B9CCE:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 22:02:09.007745 :AE7B9CCF:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 22:02:09.007746 :AE7B9CD0:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 22:02:09.007746 :AE7B9CD1:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 22:02:09.007746 :AE7B9CD2:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 22:02:09.008313 :AE7B9CDC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.252604 :AE7B9D16:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 22:02:09.253252 :AE7B9D24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.264771 :AE7B9D40:db_trace:kwqid.c@2146:kwqidrdq(): [10960:27:219] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-06 22:02:09.265192 :AE7B9D41:db_trace:kwqid.c@2146:kwqidrdq(): [10960:27:219] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-06 22:02:09.267887 :AE7B9D42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:psdpgi.c:2235 2025-07-06 22:02:09.268257 :AE7B9D43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.268297 :AE7B9D46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:psdpgi.c:2235 2025-07-06 22:02:09.269188 :AE7B9D4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-06 22:02:09.269302 :AE7B9D55:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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 27 (osid: 13332, J002)