Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250507220006\orcl_j005_10684_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:37442M/63366M, Ph+PgF:44888M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 10684, image: ORACLE.EXE (J005) *** 2025-05-07 22:00:06.921 *** SESSION ID:(249.597) 2025-05-07 22:00:06.921 *** 2025-05-07 22:00:06.921 Process diagnostic dump for ORACLE.EXE (J005), OS id=10684, pid: 31, proc_ser: 35, sid: 249, sess_ser: 597 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10684 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.346292 sec, exc=0.346292 sec, total=0.346292 sec wait times: max=0.500000 sec, heur=4.848934 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500148 sec, exc=0.500148 sec, total=0.500148 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500289 sec, exc=0.500289 sec, total=0.500289 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.506763 sec, exc=0.506763 sec, total=0.506763 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.495383 sec, exc=0.495383 sec, total=0.495383 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 249 serial 597 --------------------------------------------------- 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:00:02 - 22:00:06] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-07 22:00:06.921 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 10684, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 22:02:35.037377 :92BEDAF5:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 22:02:35.037377 :92BEDAF6:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 22:02:35.037378 :92BEDAF7:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 22:02:35.037378 :92BEDAF8:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 22:02:35.037379 :92BEDAF9:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 22:02:35.038026 :92BEDAFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-04 22:02:35.038066 :92BEDB03:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05-04 22:02:35.047135 :92BEDB15:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-04 23:59:17.910370 :92C81ED8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-04 23:59:17.898 2025-05-04 23:59:17.910372 :92C81ED9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4208 pso_num=31 pso_serial#=15 2025-05-04 23:59:17.910507 :92C81EDA:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4208 (legacy spawn) 2025-05-04 23:59:17.924226 :92C81EDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 23:59:51.156461 :92C82B69:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-04 23:59:54.686648 :92C82C8E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-04 23:59:54.676 2025-05-04 23:59:54.686650 :92C82C8F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9452 pso_num=31 pso_serial#=16 2025-05-04 23:59:54.686791 :92C82C90:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9452 (legacy spawn) 2025-05-04 23:59:54.700676 :92C82C91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 00:00:27.913797 :92C83817:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 00:02:51.126269 :92C86BEA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 00:02:51.123 2025-05-05 00:02:51.126270 :92C86BEB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=1112 pso_num=31 pso_serial#=17 2025-05-05 00:02:51.126411 :92C86BEC:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=1112 osp_idx=29 osp_ver=271 osp_pg=0 (spawn #149031) 2025-05-05 00:02:51.126412 :92C86BED:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 00:02:51.126413 :92C86BEE:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=96 time=3674148281 2025-05-05 00:02:51.126413 :92C86BEF:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 00:02:51.126414 :92C86BF0:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 00:02:51.126414 :92C86BF1:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 00:02:51.126414 :92C86BF2:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 00:02:51.126415 :92C86BF3:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 00:02:51.126947 :92C86BF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 00:02:51.127003 :92C86BFB:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05-05 00:02:51.136023 :92C86C12:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 22:00:02.061121 :9330F128:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 22:00:02.054 2025-05-05 22:00:02.061122 :9330F129:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9344 pso_num=31 pso_serial#=18 2025-05-05 22:00:02.061250 :9330F12F:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=9344 osp_idx=29 osp_ver=272 osp_pg=0 (spawn #152125) 2025-05-05 22:00:02.061251 :9330F130:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-05 22:00:02.061251 :9330F131:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=97 time=3753179218 2025-05-05 22:00:02.061252 :9330F132:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-05 22:00:02.061252 :9330F133:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-05 22:00:02.061252 :9330F134:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-05 22:00:02.061253 :9330F135:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-05 22:00:02.061253 :9330F136:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-05 22:00:02.061761 :9330F137:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-05 22:00:32.145855 :93310422:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:07.438100 :933A4C48:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:07.429 2025-05-05 23:59:07.438101 :933A4C49:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10928 pso_num=31 pso_serial#=19 2025-05-05 23:59:07.438244 :933A4C4A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10928 (legacy spawn) 2025-05-05 23:59:07.444748 :933A4C4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 23:59:07.444919 :933A4C50:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05 23:59:07.445733 :933A4C55:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:09.843266 :933A4D2A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:09.833 2025-05-05 23:59:09.843268 :933A4D2B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10496 pso_num=31 pso_serial#=20 2025-05-05 23:59:09.843400 :933A4D2C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10496 (legacy spawn) 2025-05-05 23:59:09.857355 :933A4D2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 23:59:13.151908 :933A4E2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 23:59:13.152093 :933A4E33:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05 23:59:13.153340 :933A4E34:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:13.399735 :933A4E64:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:13.393 2025-05-05 23:59:13.399737 :933A4E65:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10712 pso_num=31 pso_serial#=21 2025-05-05 23:59:13.399877 :933A4E66:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10712 (legacy spawn) 2025-05-05 23:59:13.413874 :933A4E67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 23:59:46.584144 :933A5ADB:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:46.653689 :933A5AEA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:46.643 2025-05-05 23:59:46.653691 :933A5AEB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8444 pso_num=31 pso_serial#=22 2025-05-05 23:59:46.653814 :933A5AEC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8444 (legacy spawn) 2025-05-05 23:59:46.672190 :933A5AED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 23:59:49.957938 :933A5C12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-05 23:59:49.958116 :933A5C17:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05 23:59:49.959427 :933A5C1C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-05 23:59:50.213856 :933A5C2F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-05 23:59:50.206 2025-05-05 23:59:50.213859 :933A5C30:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11644 pso_num=31 pso_serial#=23 2025-05-05 23:59:50.214004 :933A5C31:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11644 (legacy spawn) 2025-05-05 23:59:50.228254 :933A5C32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 00:00:23.411247 :933A68FA:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 00:01:13.117667 :933A7E42:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 00:01:13.112 2025-05-06 00:01:13.117669 :933A7E43:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=1164 pso_num=31 pso_serial#=24 2025-05-06 00:01:13.117816 :933A7E44:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=1164 osp_idx=29 osp_ver=278 osp_pg=0 (spawn #152415) 2025-05-06 00:01:13.117817 :933A7E45:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 00:01:13.117817 :933A7E46:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=98 time=3760450281 2025-05-06 00:01:13.117818 :933A7E47:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 00:01:13.117818 :933A7E48:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 00:01:13.117819 :933A7E49:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 00:01:13.117819 :933A7E4A:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 00:01:13.117819 :933A7E4B:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 00:01:13.118365 :933A7E4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 00:01:13.118405 :933A7E54:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05-06 00:01:13.124962 :933A7E60:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 00:06:04.834492 :933AE4A0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 00:06:04.822 2025-05-06 00:06:04.834494 :933AE4A1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11372 pso_num=31 pso_serial#=25 2025-05-06 00:06:04.834632 :933AE4A2:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11372 osp_idx=29 osp_ver=279 osp_pg=0 (spawn #152427) 2025-05-06 00:06:04.834632 :933AE4A3:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 00:06:04.834633 :933AE4A4:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=99 time=3760742000 2025-05-06 00:06:04.834633 :933AE4A5:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 00:06:04.834633 :933AE4A6:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 00:06:04.834634 :933AE4A7:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 00:06:04.834634 :933AE4A8:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 00:06:04.834634 :933AE4A9:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 00:06:04.835127 :933AE4AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 00:06:04.835168 :933AE4B0:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05-06 00:06:04.839395 :933AE4D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.839438 :933AE4DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.839684 :933AE4DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.839700 :933AE4DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.839909 :933AE4DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.839925 :933AE4DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.840133 :933AE4DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.840146 :933AE4E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.840345 :933AE4E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.840357 :933AE4E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.840628 :933AE4E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:kelt.c:517 2025-05-06 00:06:04.840647 :933AE4E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:kelt.c:517 2025-05-06 00:06:04.843031 :933AE4E5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 22:00:02.119272 :93A09A1B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 22:00:02.111 2025-05-06 22:00:02.119273 :93A09A1C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11480 pso_num=31 pso_serial#=26 2025-05-06 22:00:02.119414 :93A09A1D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11480 osp_idx=29 osp_ver=280 osp_pg=0 (spawn #155511) 2025-05-06 22:00:02.119415 :93A09A1E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 22:00:02.119415 :93A09A1F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=100 time=3839579281 2025-05-06 22:00:02.119416 :93A09A20:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 22:00:02.119416 :93A09A21:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 22:00:02.119417 :93A09A22:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 22:00:02.119417 :93A09A23:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 22:00:02.119417 :93A09A24:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 22:00:02.119968 :93A09A26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 22:00:32.183037 :93A0ACBF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:08.928020 :93A9D487:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:08.913 2025-05-06 23:59:08.928022 :93A9D488:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12016 pso_num=31 pso_serial#=27 2025-05-06 23:59:08.928153 :93A9D489:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12016 (legacy spawn) 2025-05-06 23:59:08.934761 :93A9D48A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:08.934937 :93A9D493:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:08.935774 :93A9D494:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:08.944799 :93A9D497:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:08.944 2025-05-06 23:59:08.944801 :93A9D498:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9092 pso_num=31 pso_serial#=28 2025-05-06 23:59:08.944940 :93A9D499:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9092 (legacy spawn) 2025-05-06 23:59:08.951577 :93A9D49A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:08.951753 :93A9D4A2:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:08.952629 :93A9D4A4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:10.044402 :93A9D52C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:10.038 2025-05-06 23:59:10.044404 :93A9D52D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10188 pso_num=31 pso_serial#=29 2025-05-06 23:59:10.044548 :93A9D52E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10188 (legacy spawn) 2025-05-06 23:59:10.058563 :93A9D52F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:13.304444 :93A9D64B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:13.304647 :93A9D650:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:13.305804 :93A9D655:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:13.554879 :93A9D658:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:13.550 2025-05-06 23:59:13.554881 :93A9D659:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11776 pso_num=31 pso_serial#=30 2025-05-06 23:59:13.555027 :93A9D65A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11776 (legacy spawn) 2025-05-06 23:59:13.569285 :93A9D65B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:46.729026 :93A9E217:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:48.330484 :93A9E2E2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:48.317 2025-05-06 23:59:48.330487 :93A9E2E3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4728 pso_num=31 pso_serial#=31 2025-05-06 23:59:48.330663 :93A9E2E4:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4728 (legacy spawn) 2025-05-06 23:59:48.337188 :93A9E2E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:48.337369 :93A9E2EA:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:48.338185 :93A9E2EF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:48.346935 :93A9E2F2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:48.333 2025-05-06 23:59:48.346937 :93A9E2F3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11736 pso_num=31 pso_serial#=32 2025-05-06 23:59:48.347073 :93A9E2F4:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11736 (legacy spawn) 2025-05-06 23:59:48.353540 :93A9E2F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:48.353700 :93A9E2FE:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:48.354500 :93A9E2FF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:50.910707 :93A9E3C3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:50.911 2025-05-06 23:59:50.910710 :93A9E3C4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8780 pso_num=31 pso_serial#=33 2025-05-06 23:59:50.910857 :93A9E3C5:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8780 (legacy spawn) 2025-05-06 23:59:50.924718 :93A9E3C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:54.217721 :93A9E4FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-06 23:59:54.217895 :93A9E507:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06 23:59:54.219125 :93A9E508:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-06 23:59:54.468635 :93A9E50B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-06 23:59:54.455 2025-05-06 23:59:54.468638 :93A9E50C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11548 pso_num=31 pso_serial#=34 2025-05-06 23:59:54.468773 :93A9E50D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11548 (legacy spawn) 2025-05-06 23:59:54.482735 :93A9E50E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-07 00:00:27.647135 :93A9F480:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-07 22:00:02.078929 :9411EDCC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 22:00:02.076 2025-05-07 22:00:02.078930 :9411EDCD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10684 pso_num=31 pso_serial#=35 2025-05-07 22:00:02.079062 :9411EDCE:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=10684 osp_idx=29 osp_ver=289 osp_pg=0 (spawn #158897) 2025-05-07 22:00:02.079063 :9411EDCF:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 22:00:02.079064 :9411EDD0:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=101 time=3925979234 2025-05-07 22:00:02.079065 :9411EDD1:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 22:00:02.079065 :9411EDD2:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 22:00:02.079066 :9411EDD3:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 22:00:02.079067 :9411EDD4:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 22:00:02.079068 :9411EDD5:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 22:00:02.079658 :9411EDE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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 31 (osid: 10684, J005)