Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250611220008\orcl_j006_17068_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:36742M/63366M, Ph+PgF:43286M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 32 Windows thread id: 17068, image: ORACLE.EXE (J006) *** 2025-06-11 22:00:08.622 *** SESSION ID:(2.1392) 2025-06-11 22:00:08.622 *** 2025-06-11 22:00:08.622 Process diagnostic dump for ORACLE.EXE (J006), OS id=17068, pid: 32, proc_ser: 245, sid: 2, sess_ser: 1392 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17068 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J006) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.084248 sec, exc=0.084248 sec, total=0.084248 sec wait times: max=0.500000 sec, heur=8.085946 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=15 seq_num=16 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000018 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500143 sec, exc=0.500143 sec, total=0.500143 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.501812 sec, exc=0.501812 sec, total=0.501812 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499976 sec, exc=0.499976 sec, total=0.499976 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500026 sec, exc=0.500026 sec, total=0.500026 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=8 seq_num=9 snap_id=1 wait times: snap=0.500163 sec, exc=0.500163 sec, total=0.500163 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000010 sec of elapsed time Sampled Session History of session 2 serial 1392 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:00:00 - 22:00:08] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-11 22:00:08.622 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 32 (osid: 17068, J006) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 06:00:06.351061 :A163A9EE:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 06:00:06.351061 :A163A9EF:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 06:00:06.351061 :A163A9F0:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 06:00:06.351062 :A163A9F1:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 06:00:06.351584 :A163A9F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.351631 :A163A9F8:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-06-07 06:00:06.356163 :A163AA0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.383463 :A163AA13:db_trace:ksb.c@6610:ksbcic_int(): [10254:32:2] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286502186 8465112) 2025-06-07 06:00:06.383470 :A163AA14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.383524 :A163AA1F:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 06:00:06.383563 :A163AA2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.398252 :A163AA34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.426238 :A163AA3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.429931 :A163AA44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.433764 :A163AA4C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.438102 :A163AA54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.439226 :A163AA5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:06.462479 :A163AA64:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 4, ext 1, nex 3 2025-06-07 06:00:06.462485 :A163AA65:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x00812af9 where: kdtwh01: kdtgrs 2025-06-07 06:00:06.485011 :A163AA66:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 4, ext 2, nex 4 2025-06-07 06:00:06.485019 :A163AA67:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x00812b1d where: kdiwh22: kdifind 2025-06-07 06:00:06.497270 :A163AA6E:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 4, ext 3, nex 5 2025-06-07 06:00:06.497280 :A163AA6F:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x00812796 where: kdiwh22: kdifind 2025-06-07 06:00:07.069834 :A163AB11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:07.069852 :A163AB12:db_trace:ktur.c@3079:ktuabt(): [10444:32:2] ABORT TRANSACTION - xid: 0x0004.016.0000c212 2025-06-07 06:00:07.070267 :A163AB1A:db_trace:ktu.c@4871:ktuaex1r(): [10442:32:2] Extension usn 4, ext 4, nex 6 2025-06-07 06:00:07.070275 :A163AB1B:db_trace:ktusm.c@2117:ktusmasp(): [10445:32:2] des1:tsn:1 rdba:0x0081279b where: ktspbwh1: ktspfsrch 2025-06-07 06:00:07.645903*:A163AB50:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000320D1500 estart=1749247206 eid=16779034 ctx=0x0000000777AAE258 cwh=0x0000000032090918 cpu=31250 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-06-07 06:00:07.645903*:A163AB51:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000320D1500 curCtx=0x000000003271F998 pubTabIdxCnt=0 2025-06-07 06:00:07.645903*:A163AB52:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000320D1500 2025-06-07 06:00:07.645903*:A163AB53:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000320D1500 2025-06-07 06:00:07.661532*:A163AB54:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000320D1500 curCtx=0x000000003271F998 xsc->flg4=65792 flags=2 2025-06-07 06:00:07.661532*:A163AB55:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000320D1500 2025-06-07 06:00:07.694076 :A163AB56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:07.730149 :A163AB5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:07.730478 :A163AB66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:07.730757 :A163AB6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:07.730773 :A163AB71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:07.730838 :A163AB7F:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 06:00:07.734824 :A163AB8B:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-07 23:59:13.067923 :A1B8E2CC:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-07 23:59:13.054 2025-06-07 23:59:13.067926 :A1B8E2CD:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=14872 pso_num=32 pso_serial#=226 2025-06-07 23:59:13.068062 :A1B8E2CE:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=14872 (legacy spawn) 2025-06-07 23:59:13.074664 :A1B8E2CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 23:59:13.074832 :A1B8E2D4:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 23:59:13.075712 :A1B8E2D9:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-07 23:59:14.033748 :A1B8E316:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-07 23:59:14.028 2025-06-07 23:59:14.033750 :A1B8E317:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=14836 pso_num=32 pso_serial#=227 2025-06-07 23:59:14.033878 :A1B8E318:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=14836 (legacy spawn) 2025-06-07 23:59:14.047867 :A1B8E319:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 23:59:47.207356 :A1B8EF24:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-07 23:59:54.905202 :A1B8F1BA:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-07 23:59:54.894 2025-06-07 23:59:54.905204 :A1B8F1BB:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16200 pso_num=32 pso_serial#=228 2025-06-07 23:59:54.905341 :A1B8F1BC:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=16200 (legacy spawn) 2025-06-07 23:59:54.919113 :A1B8F1BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 00:00:28.108079 :A1B90118:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-08 06:00:14.701705 :A1D4C39E:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-08 06:00:14.691 2025-06-08 06:00:14.701708 :A1D4C39F:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=14280 pso_num=32 pso_serial#=229 2025-06-08 06:00:14.701840 :A1D4C3A0:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=14280 osp_idx=30 osp_ver=483 osp_pg=0 (spawn #263594) 2025-06-08 06:00:14.701841 :A1D4C3A1:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-08 06:00:14.701841 :A1D4C3A2:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=115 time=2338157891 2025-06-08 06:00:14.701842 :A1D4C3A3:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-06-08 06:00:14.701842 :A1D4C3A4:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-06-08 06:00:14.701843 :A1D4C3A5:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-06-08 06:00:14.701843 :A1D4C3A6:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-08 06:00:14.701843 :A1D4C3A7:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-08 06:00:14.702336 :A1D4C3A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.702378 :A1D4C3AE:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] 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-06-08 06:00:14.709568 :A1D4C3BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.710611 :A1D4C3C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.711159 :A1D4C3CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.711620 :A1D4C3D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.712049 :A1D4C3DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.714530 :A1D4C3E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.715610 :A1D4C3EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.765479 :A1D4C3F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.766427 :A1D4C3FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.766675 :A1D4C404:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.766897 :A1D4C40C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 06:00:14.768591 :A1D4C414:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-08 23:59:10.175641 :A228F824:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-08 23:59:10.167 2025-06-08 23:59:10.175643 :A228F825:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16132 pso_num=32 pso_serial#=230 2025-06-08 23:59:10.175779 :A228F826:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=16132 (legacy spawn) 2025-06-08 23:59:10.182572 :A228F827:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 23:59:10.182741 :A228F82F:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-08 23:59:10.183595 :A228F831:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-08 23:59:13.960905 :A228F992:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-08 23:59:13.958 2025-06-08 23:59:13.960907 :A228F993:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=12276 pso_num=32 pso_serial#=231 2025-06-08 23:59:13.961041 :A228F994:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=12276 (legacy spawn) 2025-06-08 23:59:13.975248 :A228F995:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-08 23:59:47.108136 :A2290578:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-08 23:59:54.849905 :A22907EE:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-08 23:59:54.850 2025-06-08 23:59:54.849908 :A22907EF:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=11632 pso_num=32 pso_serial#=232 2025-06-08 23:59:54.850051 :A22907F0:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=11632 (legacy spawn) 2025-06-08 23:59:54.864744 :A22907F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 00:00:28.008057 :A229181B:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-09 23:59:13.890702 :A298896B:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-09 23:59:13.882 2025-06-09 23:59:13.890704 :A298896C:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=15744 pso_num=32 pso_serial#=233 2025-06-09 23:59:13.890842 :A298896D:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=15744 (legacy spawn) 2025-06-09 23:59:13.906234 :A298896E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 23:59:47.253665 :A2989580:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-09 23:59:54.886108 :A29897EB:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-09 23:59:54.881 2025-06-09 23:59:54.886110 :A29897EC:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=15760 pso_num=32 pso_serial#=234 2025-06-09 23:59:54.886248 :A29897ED:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=15760 (legacy spawn) 2025-06-09 23:59:54.900402 :A29897EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 00:00:28.142660 :A298A5E0:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:10.577852 :A307512C:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:10.565 2025-06-10 23:59:10.577854 :A307512D:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=8432 pso_num=32 pso_serial#=235 2025-06-10 23:59:10.577995 :A307512E:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=8432 (legacy spawn) 2025-06-10 23:59:10.584524 :A307512F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:10.584713 :A3075137:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:10.585511 :A3075139:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:10.604104 :A307513C:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:10.597 2025-06-10 23:59:10.604106 :A307513D:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=15240 pso_num=32 pso_serial#=236 2025-06-10 23:59:10.604246 :A307513E:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=15240 (legacy spawn) 2025-06-10 23:59:10.610747 :A307513F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:10.610921 :A3075148:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:10.611749 :A3075149:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:13.771321 :A3075298:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:13.757 2025-06-10 23:59:13.771323 :A3075299:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16808 pso_num=32 pso_serial#=237 2025-06-10 23:59:13.771470 :A307529A:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=16808 (legacy spawn) 2025-06-10 23:59:13.785369 :A307529B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:16.722233 :A3075383:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:16.722406 :A3075388:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:16.723516 :A307538D:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:17.455554 :A30753D3:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:17.452 2025-06-10 23:59:17.455557 :A30753D4:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=14308 pso_num=32 pso_serial#=238 2025-06-10 23:59:17.455698 :A30753D5:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=14308 (legacy spawn) 2025-06-10 23:59:17.469950 :A30753D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:20.448172 :A30754C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:20.448355 :A30754CD:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:20.449625 :A30754D2:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:21.242706 :A30754F8:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:21.238 2025-06-10 23:59:21.242708 :A30754F9:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=13912 pso_num=32 pso_serial#=239 2025-06-10 23:59:21.242848 :A30754FA:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=13912 (legacy spawn) 2025-06-10 23:59:21.257008 :A30754FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:24.423765 :A3075617:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:24.423950 :A3075620:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:24.425241 :A3075621:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:25.249407 :A3075657:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:25.246 2025-06-10 23:59:25.249409 :A3075658:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=10824 pso_num=32 pso_serial#=240 2025-06-10 23:59:25.249540 :A3075659:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=10824 (legacy spawn) 2025-06-10 23:59:25.263196 :A307565A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:28.522431 :A3075763:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:28.522614 :A3075768:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:28.523702 :A307576D:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:29.355351 :A3075799:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:29.345 2025-06-10 23:59:29.355354 :A307579A:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=9288 pso_num=32 pso_serial#=241 2025-06-10 23:59:29.355483 :A307579B:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=9288 (legacy spawn) 2025-06-10 23:59:29.369897 :A307579C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:32.577590 :A3075905:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:32.577758 :A307590E:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:32.578893 :A307590F:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:33.311887 :A3075912:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:33.306 2025-06-10 23:59:33.311889 :A3075913:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16412 pso_num=32 pso_serial#=242 2025-06-10 23:59:33.312032 :A3075914:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=16412 (legacy spawn) 2025-06-10 23:59:33.325901 :A3075915:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:36.160926 :A3075A26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:36.161086 :A3075A2B:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:36.162138 :A3075A30:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:36.903661 :A3075A85:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:36.890 2025-06-10 23:59:36.903663 :A3075A86:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17256 pso_num=32 pso_serial#=243 2025-06-10 23:59:36.903799 :A3075A87:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=17256 (legacy spawn) 2025-06-10 23:59:36.917889 :A3075A88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:39.759089 :A3075B98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-10 23:59:39.759275 :A3075BA0:db_trace:ksl2.c@12699:ksliwat(): [10005:32:2] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 23:59:39.760416 :A3075BA2:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-10 23:59:55.064469 :A30760ED:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-10 23:59:55.063 2025-06-10 23:59:55.064471 :A30760EE:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=16160 pso_num=32 pso_serial#=244 2025-06-10 23:59:55.064622 :A30760EF:db_trace:kso.c@4070:ksonfy(): [10420:32:0] kso: new process: pid=16160 (legacy spawn) 2025-06-10 23:59:55.079115 :A30760F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-11 00:00:28.138380 :A3076F92:db_trace:kst.c@698:kstpsodel(): [10280:32:0] kst: process state object about to be deleted 2025-06-11 22:00:00.541263 :A36D358F:db_trace:kst.c@673:kstipg(): [10280:32:0] kst: process state object created on 06-11 22:00:00.531 2025-06-11 22:00:00.541265 :A36D3590:db_trace:kst.c@679:kstipg(): [10280:32:0] kst: process info: ospid=17068 pso_num=32 pso_serial#=245 2025-06-11 22:00:00.541385 :A36D3591:db_trace:kso.c@4093:ksonfy(): [10420:32:0] kso: new process: pid=17068 osp_idx=30 osp_ver=499 osp_pg=0 (spawn #275815) 2025-06-11 22:00:00.541386 :A36D3592:db_trace:kso.c@4099:ksonfy(): [10420:32:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-11 22:00:00.541386 :A36D3593:db_trace:kso.c@4103:ksonfy(): [10420:32:0] kso: new process: pso_index=32 reservation=116 time=2654943719 2025-06-11 22:00:00.541386 :A36D3594:db_trace:kso.c@4109:ksonfy(): [10420:32:0] kso: spawn diagnostics: queue time=0 secs 2025-06-11 22:00:00.541387 :A36D3595:db_trace:kso.c@4111:ksonfy(): [10420:32:0] kso: spawn diagnostics: fork time=0 secs 2025-06-11 22:00:00.541387 :A36D3596:db_trace:kso.c@4113:ksonfy(): [10420:32:0] kso: spawn diagnostics: exec time=0 secs 2025-06-11 22:00:00.541387 :A36D3597:db_trace:kso.c@4115:ksonfy(): [10420:32:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-11 22:00:00.541388 :A36D3598:db_trace:kso.c@4117:ksonfy(): [10420:32:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-11 22:00:00.541960 :A36D35A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:32:2] 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 32 (osid: 17068, J006)