Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250816220848\orcl_j001_15660_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:35443M/63366M, Ph+PgF:40773M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15660, image: ORACLE.EXE (J001) *** 2025-08-16 22:08:48.167 *** SESSION ID:(193.4975) 2025-08-16 22:08:48.167 *** 2025-08-16 22:08:48.167 Process diagnostic dump for ORACLE.EXE (J001), OS id=15660, pid: 24, proc_ser: 164, sid: 193, sess_ser: 4975 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15660 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.185004 sec, exc=0.185004 sec, total=0.185004 sec wait times: max=0.500000 sec, heur=4.189573 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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=6 seq_num=7 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.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.505089 sec, exc=0.505089 sec, total=0.505089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.502079 sec, exc=0.502079 sec, total=0.502079 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=1 seq_num=2 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.497325 sec, exc=0.497325 sec, total=0.497325 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 4975 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:08:43 - 22:08:48] idle wait at each sample [session created at: 22:08:43] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-16 22:08:48.167 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15660, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-16 22:02:15.537440 :C0AA8540:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:02:15.537440 :C0AA8541:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=95 time=4062404845 2025-08-16 22:02:15.537441 :C0AA8542:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:02:15.537441 :C0AA8543:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:02:15.537442 :C0AA8544:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:02:15.537442 :C0AA8545:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:02:15.537442 :C0AA8546:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:02:15.537917 :C0AA8547:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:02:45.626587 :C0AA903A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:03:15.635995 :C0AA99D7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:03:15.625 2025-08-16 22:03:15.635997 :C0AA99D8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19368 pso_num=24 pso_serial#=154 2025-08-16 22:03:15.636137 :C0AA99D9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19368 osp_idx=22 osp_ver=738899 osp_pg=0 (spawn #495824) 2025-08-16 22:03:15.636138 :C0AA99DA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:03:15.636138 :C0AA99DB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=96 time=4062464939 2025-08-16 22:03:15.636138 :C0AA99DC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:03:15.636139 :C0AA99DD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:03:15.636139 :C0AA99DE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:03:15.636140 :C0AA99DF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:03:15.636140 :C0AA99E0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:03:15.636614 :C0AA99E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:03:30.646086 :C0AA9E9F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 22:04:00.746751 :C0AAA89F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:04:15.737039 :C0AAADC0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:04:15.726 2025-08-16 22:04:15.737041 :C0AAADC1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18664 pso_num=24 pso_serial#=155 2025-08-16 22:04:15.737162 :C0AAADC2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18664 osp_idx=22 osp_ver=738900 osp_pg=0 (spawn #495828) 2025-08-16 22:04:15.737163 :C0AAADC3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:04:15.737164 :C0AAADC4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=97 time=4062525033 2025-08-16 22:04:15.737164 :C0AAADC5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:04:15.737165 :C0AAADC6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:04:15.737165 :C0AAADC7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:04:15.737166 :C0AAADC8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:04:15.737166 :C0AAADC9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:04:15.737717 :C0AAADCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:04:45.861669 :C0AAB88E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:05:15.856459 :C0AAC297:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:05:15.844 2025-08-16 22:05:15.856461 :C0AAC298:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19824 pso_num=24 pso_serial#=156 2025-08-16 22:05:15.856576 :C0AAC299:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19824 osp_idx=22 osp_ver=738901 osp_pg=0 (spawn #495830) 2025-08-16 22:05:15.856577 :C0AAC29A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:05:15.856578 :C0AAC29B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=98 time=4062585158 2025-08-16 22:05:15.856578 :C0AAC29C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:05:15.856578 :C0AAC29D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:05:15.856579 :C0AAC29E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:05:15.856579 :C0AAC29F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:05:15.856579 :C0AAC2A0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:05:15.857142 :C0AAC2A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:05:45.959664 :C0AAD2FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:06:03.559325 :C0AB119E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:06:03.551 2025-08-16 22:06:03.559329 :C0AB119F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20088 pso_num=24 pso_serial#=157 2025-08-16 22:06:03.559488 :C0AB11A0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20088 osp_idx=22 osp_ver=738902 osp_pg=0 (spawn #495832) 2025-08-16 22:06:03.559489 :C0AB11A1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:06:03.559489 :C0AB11A2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=99 time=4062632861 2025-08-16 22:06:03.559490 :C0AB11A3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:06:03.559490 :C0AB11A4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:06:03.559490 :C0AB11A5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:06:03.559491 :C0AB11A6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:06:03.559491 :C0AB11A7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:06:03.560317 :C0AB11A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:06:03.560362 :C0AB11AE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 22:06:03.662330 :C0AB11E0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:06:05.937848 :C0AB1320:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:06:05.937 2025-08-16 22:06:05.937850 :C0AB1321:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20916 pso_num=24 pso_serial#=158 2025-08-16 22:06:05.937990 :C0AB1322:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20916 osp_idx=22 osp_ver=738903 osp_pg=0 (spawn #495834) 2025-08-16 22:06:05.937991 :C0AB1323:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:06:05.937991 :C0AB1324:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=100 time=4062635236 2025-08-16 22:06:05.937992 :C0AB1325:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:06:05.937992 :C0AB1326:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:06:05.937992 :C0AB1327:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:06:05.937993 :C0AB1328:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:06:05.937993 :C0AB1329:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:06:05.938499 :C0AB132A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:06:05.938541 :C0AB1330:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 22:06:08.738486 :C0AB1A9F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:06:10.044158 :C0AB1FA7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:06:10.032 2025-08-16 22:06:10.044160 :C0AB1FA8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20756 pso_num=24 pso_serial#=159 2025-08-16 22:06:10.044317 :C0AB1FA9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20756 (legacy spawn) 2025-08-16 22:06:10.051596 :C0AB1FDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:10.051739 :C0AB1FE6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-16 22:06:10.052670 :C0AB1FE7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:06:10.062406 :C0AB1FEA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:06:10.048 2025-08-16 22:06:10.062408 :C0AB1FEB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21316 pso_num=24 pso_serial#=160 2025-08-16 22:06:10.062586 :C0AB1FEC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21316 (legacy spawn) 2025-08-16 22:06:10.069690 :C0AB1FFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:10.069864 :C0AB2005:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-16 22:06:10.070831 :C0AB200F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:06:10.136935 :C0AB2094:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:06:10.126 2025-08-16 22:06:10.136937 :C0AB2095:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20544 pso_num=24 pso_serial#=161 2025-08-16 22:06:10.137086 :C0AB2096:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20544 (legacy spawn) 2025-08-16 22:06:10.144464 :C0AB20AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:06:10.144675 :C0AB20B2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-16 22:06:10.145668 :C0AB20B4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:06:15.922127 :C0AB2903:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:06:15.911 2025-08-16 22:06:15.922129 :C0AB2904:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19984 pso_num=24 pso_serial#=162 2025-08-16 22:06:15.922267 :C0AB2905:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19984 osp_idx=22 osp_ver=738907 osp_pg=0 (spawn #495837) 2025-08-16 22:06:15.922268 :C0AB2906:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:06:15.922268 :C0AB2907:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=101 time=4062645220 2025-08-16 22:06:15.922269 :C0AB2908:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:06:15.922269 :C0AB2909:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:06:15.922269 :C0AB290A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:06:15.922270 :C0AB290B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:06:15.922270 :C0AB290C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:06:15.922773 :C0AB290D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:06:46.018088 :C0AB32DB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:07:16.013908 :C0AB3C9A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:07:16.002 2025-08-16 22:07:16.013910 :C0AB3C9B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22160 pso_num=24 pso_serial#=163 2025-08-16 22:07:16.014048 :C0AB3C9C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22160 osp_idx=22 osp_ver=738908 osp_pg=0 (spawn #495839) 2025-08-16 22:07:16.014048 :C0AB3C9D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:07:16.014049 :C0AB3C9E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=102 time=4062705314 2025-08-16 22:07:16.014049 :C0AB3C9F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:07:16.014050 :C0AB3CA0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:07:16.014050 :C0AB3CA1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:07:16.014050 :C0AB3CA2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:07:16.014050 :C0AB3CA3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:07:16.014532 :C0AB3CA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:07:46.096652 :C0AB4693:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-16 22:08:16.131182 :C0AB507F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-16 22:08:16.119 2025-08-16 22:08:16.131184 :C0AB5080:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15660 pso_num=24 pso_serial#=164 2025-08-16 22:08:16.131303 :C0AB5081:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15660 osp_idx=22 osp_ver=738909 osp_pg=0 (spawn #495841) 2025-08-16 22:08:16.131304 :C0AB5082:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-16 22:08:16.131304 :C0AB5083:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=103 time=4062765423 2025-08-16 22:08:16.131304 :C0AB5084:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-16 22:08:16.131305 :C0AB5085:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-16 22:08:16.131305 :C0AB5086:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-16 22:08:16.131305 :C0AB5087:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-16 22:08:16.131306 :C0AB5088:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-16 22:08:16.131790 :C0AB5089:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-16 22:08:42.200585 :C0AB5AB0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 22:08:42.306893 :C0AB5FB9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-16 22:08:42.306893*:C0AB5FC8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1755353321 eid=16786216 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-16 22:08:42.306893*:C0AB5FC9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-08-16 22:08:42.306893*:C0AB5FCA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-08-16 22:08:42.306893*:C0AB5FCB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-08-16 22:08:42.306893*:C0AB5FCC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-08-16 22:08:42.306893*:C0AB5FCD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-08-16 22:08:42.312609 :C0AB5FD8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:42.312666 :C0AB5FDB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-16 22:08:42.322823 :C0AB5FEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:42.436946 :C0AB5FFF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:42.572054 :C0AB6007:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:42.702754 :C0AB600F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.827745 :C0AB607D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.828744 :C0AB6085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.830454 :C0AB608D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.832080 :C0AB6095:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.835586 :C0AB609D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.851880 :C0AB60A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.852582 :C0AB60AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.853154 :C0AB60B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.853668 :C0AB60BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.856344 :C0AB60C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.857312 :C0AB60CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.858102 :C0AB60CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.858884 :C0AB60CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.859391 :C0AB60D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.859398 :C0AB60D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.859992 :C0AB60D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.860514 :C0AB60D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.860841 :C0AB60D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.861907 :C0AB60D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.905045 :C0AB60D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.905094 :C0AB60D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.906023 :C0AB60DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.906903 :C0AB60E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.907416 :C0AB60E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.927393 :C0AB60F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.927444 :C0AB60F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.928337 :C0AB60F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.929239 :C0AB6101:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.929694 :C0AB6102:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.947321 :C0AB610A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.947372 :C0AB610D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.948280 :C0AB6113:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.949126 :C0AB611B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-16 22:08:43.949599 :C0AB611C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.970752 :C0AB6126:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.970806 :C0AB6129:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-16 22:08:43.971846 :C0AB613C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.972353 :C0AB6144:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.972715 :C0AB614C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.972910 :C0AB6154:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.973058 :C0AB615C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.973207 :C0AB6164:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-16 22:08:43.974633 :C0AB6165:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.977522 :C0AB616D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.977610 :C0AB6170:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-16 22:08:43.977685 :C0AB617D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 15660, J001)