Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503140813\orcl_j002_10836_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:37580M/63366M, Ph+PgF:45110M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 10836, image: ORACLE.EXE (J002) *** 2025-05-03 14:08:13.852 *** SESSION ID:(219.23753) 2025-05-03 14:08:13.852 *** 2025-05-03 14:08:13.852 Process diagnostic dump for ORACLE.EXE (J002), OS id=10836, pid: 27, proc_ser: 236, sid: 219, sess_ser: 23753 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10836 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.405001 sec, exc=0.405001 sec, total=0.405001 sec wait times: max=0.500000 sec, heur=6.408852 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 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=10 seq_num=11 snap_id=1 wait times: snap=0.500120 sec, exc=0.500120 sec, total=0.500120 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500103 sec, exc=0.500103 sec, total=0.500103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000012 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500102 sec, exc=0.500102 sec, total=0.500102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000014 sec of elapsed time Sampled Session History of session 219 serial 23753 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 14:08:07 - 14:08:13] idle wait at each sample [session created at: 14:08:07] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-03 14:08:13.852 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 10836, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-03 14:03:32.160255 :9225BF2B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8872 (legacy spawn) 2025-05-03 14:03:32.166603 :9225BF2C: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-05-03 14:03:32.166772 :9225BF31: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-05-03 14:03:32.167570 :9225BF36:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:03:40.515103 :9225C1F2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:03:40.500 2025-05-03 14:03:40.515105 :9225C1F3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11220 pso_num=27 pso_serial#=209 2025-05-03 14:03:40.515244 :9225C1F4:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11220 (legacy spawn) 2025-05-03 14:03:40.521687 :9225C1F5: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-05-03 14:03:40.521857 :9225C1FE: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-05-03 14:03:40.522647 :9225C1FF:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:03:40.531278 :9225C202:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:03:40.516 2025-05-03 14:03:40.531280 :9225C203:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9208 pso_num=27 pso_serial#=210 2025-05-03 14:03:40.531418 :9225C204:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9208 (legacy spawn) 2025-05-03 14:03:40.537957 :9225C205: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-05-03 14:03:40.538125 :9225C20E: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-05-03 14:03:40.538964 :9225C20F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:03:43.197457 :9225C2BF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:03:43.190 2025-05-03 14:03:43.197459 :9225C2C0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10636 pso_num=27 pso_serial#=211 2025-05-03 14:03:43.197597 :9225C2C1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10636 (legacy spawn) 2025-05-03 14:03:43.204052 :9225C2C2: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-05-03 14:03:43.204223 :9225C2C7: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-05-03 14:03:43.205011 :9225C2CC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:03:43.812483 :9225C31B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:03:43.799 2025-05-03 14:03:43.812485 :9225C31C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8140 pso_num=27 pso_serial#=212 2025-05-03 14:03:43.812624 :9225C31D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8140 (legacy spawn) 2025-05-03 14:03:43.819065 :9225C31E: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-05-03 14:03:43.819232 :9225C327: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-05-03 14:03:43.820024 :9225C328:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:03:43.828595 :9225C32B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:03:43.815 2025-05-03 14:03:43.828597 :9225C32C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12124 pso_num=27 pso_serial#=213 2025-05-03 14:03:43.828733 :9225C32D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12124 (legacy spawn) 2025-05-03 14:03:43.835162 :9225C32E: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-05-03 14:03:43.835327 :9225C337: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-05-03 14:03:43.836142 :9225C338:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:20.114150 :9225CF6E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:20.101 2025-05-03 14:04:20.114153 :9225CF6F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4320 pso_num=27 pso_serial#=214 2025-05-03 14:04:20.114292 :9225CF70:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4320 (legacy spawn) 2025-05-03 14:04:20.120866 :9225CF71: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-05-03 14:04:20.121040 :9225CF76: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-05-03 14:04:20.121819 :9225CF7B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:20.130393 :9225CF7E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:20.116 2025-05-03 14:04:20.130395 :9225CF7F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10200 pso_num=27 pso_serial#=215 2025-05-03 14:04:20.130531 :9225CF80:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10200 (legacy spawn) 2025-05-03 14:04:20.137008 :9225CF81: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-05-03 14:04:20.137169 :9225CF8A: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-05-03 14:04:20.137957 :9225CF8B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:38.326150 :9225D676:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:38.324 2025-05-03 14:04:38.326153 :9225D677:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10864 pso_num=27 pso_serial#=216 2025-05-03 14:04:38.326345 :9225D678:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10864 (legacy spawn) 2025-05-03 14:04:38.333023 :9225D679: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-05-03 14:04:38.333204 :9225D67E: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-05-03 14:04:38.334064 :9225D683:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:38.364439 :9225D686:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:38.355 2025-05-03 14:04:38.364441 :9225D687:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8936 pso_num=27 pso_serial#=217 2025-05-03 14:04:38.364576 :9225D688:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8936 (legacy spawn) 2025-05-03 14:04:38.371184 :9225D689: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-05-03 14:04:38.371353 :9225D692: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-05-03 14:04:38.372208 :9225D693:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:40.348373 :9225D72C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:40.340 2025-05-03 14:04:40.348375 :9225D72D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12108 pso_num=27 pso_serial#=218 2025-05-03 14:04:40.348518 :9225D72E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12108 (legacy spawn) 2025-05-03 14:04:40.354946 :9225D72F: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-05-03 14:04:40.355144 :9225D734: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-05-03 14:04:40.355963 :9225D747:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:45.373624 :9225D8CA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:45.365 2025-05-03 14:04:45.373627 :9225D8CB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9648 pso_num=27 pso_serial#=219 2025-05-03 14:04:45.373766 :9225D8CC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9648 (legacy spawn) 2025-05-03 14:04:45.380157 :9225D8CD: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-05-03 14:04:45.380322 :9225D8D2: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-05-03 14:04:45.381103 :9225D8D7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:53.320421 :9225DB74:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:53.306 2025-05-03 14:04:53.320424 :9225DB75:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11600 pso_num=27 pso_serial#=220 2025-05-03 14:04:53.320580 :9225DB76:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11600 (legacy spawn) 2025-05-03 14:04:53.326937 :9225DB77: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-05-03 14:04:53.327115 :9225DB80: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-05-03 14:04:53.327987 :9225DB81:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:04:53.345724 :9225DB84:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:04:53.337 2025-05-03 14:04:53.345726 :9225DB85:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=5976 pso_num=27 pso_serial#=221 2025-05-03 14:04:53.345869 :9225DB86:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=5976 (legacy spawn) 2025-05-03 14:04:53.352351 :9225DB87: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-05-03 14:04:53.352528 :9225DB90: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-05-03 14:04:53.353365 :9225DB91:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:29.613910 :9225EEAB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:29.611 2025-05-03 14:05:29.613912 :9225EEAC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10080 pso_num=27 pso_serial#=222 2025-05-03 14:05:29.614043 :9225EEAD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10080 (legacy spawn) 2025-05-03 14:05:29.620439 :9225EEAE: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-05-03 14:05:29.620610 :9225EEB7: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-05-03 14:05:29.621448 :9225EEB8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:29.630109 :9225EEBB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:29.627 2025-05-03 14:05:29.630111 :9225EEBC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11500 pso_num=27 pso_serial#=223 2025-05-03 14:05:29.630246 :9225EEBD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11500 (legacy spawn) 2025-05-03 14:05:29.636612 :9225EEBE: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-05-03 14:05:29.636784 :9225EEC7: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-05-03 14:05:29.637564 :9225EEC8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:32.722279 :9225EFB6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:32.709 2025-05-03 14:05:32.722282 :9225EFB7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1248 pso_num=27 pso_serial#=224 2025-05-03 14:05:32.722423 :9225EFB8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1248 (legacy spawn) 2025-05-03 14:05:32.728922 :9225EFB9: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-05-03 14:05:32.729092 :9225EFBE: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-05-03 14:05:32.729913 :9225EFC3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:32.738533 :9225EFC6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:32.725 2025-05-03 14:05:32.738536 :9225EFC7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9492 pso_num=27 pso_serial#=225 2025-05-03 14:05:32.738686 :9225EFC8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9492 (legacy spawn) 2025-05-03 14:05:32.745126 :9225EFC9: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-05-03 14:05:32.745294 :9225EFD2: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-05-03 14:05:32.746099 :9225EFD3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:39.248384 :9225F1D5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:39.240 2025-05-03 14:05:39.248386 :9225F1D6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11016 pso_num=27 pso_serial#=226 2025-05-03 14:05:39.248526 :9225F1D7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11016 (legacy spawn) 2025-05-03 14:05:39.254975 :9225F1D8: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-05-03 14:05:39.313115 :9225F1DD: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-05-03 14:05:39.314036 :9225F1E2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:47.612647 :9225F494:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:47.605 2025-05-03 14:05:47.612649 :9225F495:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11272 pso_num=27 pso_serial#=227 2025-05-03 14:05:47.612790 :9225F496:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11272 (legacy spawn) 2025-05-03 14:05:47.619282 :9225F497: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-05-03 14:05:47.619455 :9225F49D: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-05-03 14:05:47.620248 :9225F4A1:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:05:48.566463 :9225F4C0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:05:48.558 2025-05-03 14:05:48.566466 :9225F4C1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11040 pso_num=27 pso_serial#=228 2025-05-03 14:05:48.566611 :9225F4C2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11040 (legacy spawn) 2025-05-03 14:05:48.573079 :9225F4C3: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-05-03 14:05:48.573255 :9225F4C8: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-05-03 14:05:48.574077 :9225F4CD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:06:42.313952 :922606BE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:06:42.311 2025-05-03 14:06:42.313954 :922606BF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11388 pso_num=27 pso_serial#=229 2025-05-03 14:06:42.314150 :922606C0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11388 (legacy spawn) 2025-05-03 14:06:42.657272 :922606C1: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-05-03 14:06:42.657455 :922606C6: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-05-03 14:06:42.658273 :922606CB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:06:42.667022 :922606CE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:06:42.654 2025-05-03 14:06:42.667024 :922606CF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10720 pso_num=27 pso_serial#=230 2025-05-03 14:06:42.667157 :922606D0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10720 (legacy spawn) 2025-05-03 14:06:42.673615 :922606D1: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-05-03 14:06:42.673783 :922606DA: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-05-03 14:06:42.674597 :922606DB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:06:45.903623 :922607D8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:06:45.890 2025-05-03 14:06:45.903625 :922607D9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11472 pso_num=27 pso_serial#=231 2025-05-03 14:06:45.903767 :922607DA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11472 (legacy spawn) 2025-05-03 14:06:45.910255 :922607DB: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-05-03 14:06:45.910421 :922607E0: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-05-03 14:06:45.911209 :922607E5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:06:45.919823 :922607E8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:06:45.906 2025-05-03 14:06:45.919825 :922607E9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10084 pso_num=27 pso_serial#=232 2025-05-03 14:06:45.919959 :922607EA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10084 (legacy spawn) 2025-05-03 14:06:45.926388 :922607EB: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-05-03 14:06:45.926558 :922607F4: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-05-03 14:06:45.927375 :922607F5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:06:49.800156 :92260988:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:06:49.791 2025-05-03 14:06:49.800158 :92260989:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10360 pso_num=27 pso_serial#=233 2025-05-03 14:06:49.800302 :9226098A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=10360 (legacy spawn) 2025-05-03 14:06:49.806804 :9226098B: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-05-03 14:06:49.806978 :92260994: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-05-03 14:06:49.807820 :92260995:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:07:30.957155 :9226177E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:07:30.946 2025-05-03 14:07:30.957157 :9226177F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12248 pso_num=27 pso_serial#=234 2025-05-03 14:07:30.957285 :92261780:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12248 (legacy spawn) 2025-05-03 14:07:30.963709 :92261781: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-05-03 14:07:30.963883 :92261786: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-05-03 14:07:30.964713 :9226178B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:07:51.951317 :92261E26:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:07:51.943 2025-05-03 14:07:51.951319 :92261E27:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1484 pso_num=27 pso_serial#=235 2025-05-03 14:07:51.951456 :92261E28:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1484 (legacy spawn) 2025-05-03 14:07:51.957891 :92261E29: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-05-03 14:07:51.958068 :92261E2E: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-05-03 14:07:51.958890 :92261E33:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-05-03 14:08:07.456531 :92262817:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 05-03 14:08:07.451 2025-05-03 14:08:07.456533 :92262818:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=10836 pso_num=27 pso_serial#=236 2025-05-03 14:08:07.456661 :92262819:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=10836 osp_idx=25 osp_ver=44605 osp_pg=0 (spawn #144224) 2025-05-03 14:08:07.456662 :9226281A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 14:08:07.456662 :9226281B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=81 time=3552076937 2025-05-03 14:08:07.456662 :9226281C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 14:08:07.456663 :9226281D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 14:08:07.456663 :9226281E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 14:08:07.456663 :9226281F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 14:08:07.456664 :92262820:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 14:08:07.457191 :92262825: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 10836, J002)