Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250707220007\orcl_j005_17388_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:36639M/63366M, Ph+PgF:42679M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 17388, image: ORACLE.EXE (J005) *** 2025-07-07 22:00:07.275 *** SESSION ID:(249.1301) 2025-07-07 22:00:07.275 *** 2025-07-07 22:00:07.275 Process diagnostic dump for ORACLE.EXE (J005), OS id=17388, pid: 31, proc_ser: 122, sid: 249, sess_ser: 1301 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17388 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.414120 sec, exc=0.414120 sec, total=0.414120 sec wait times: max=0.500000 sec, heur=6.403759 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.502107 sec, exc=0.502107 sec, total=0.502107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.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.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.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.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.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.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.501104 sec, exc=0.501104 sec, total=0.501104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000009 sec of elapsed time Sampled Session History of session 249 serial 1301 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:00:00 - 22:00:07] 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-07-07 22:00:07.275 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 17388, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-04 22:00:02.047516 :AD9370AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:jslv.c:9203 2025-07-04 22:00:02.052876 :AD9370CC: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-07-04 22:00:02.258981 :AD9377B6: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-07-04 22:00:02.454960 :AD9377EE: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-07-04 22:00:02.591173 :AD93781A: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-07-04 22:00:02.712188 :AD93783F: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-07-04 22:00:03.713349 :AD937AFD: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-07-04 22:00:03.714363 :AD937B05: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-07-04 22:00:03.716138 :AD937B0D: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-07-04 22:00:03.720142 :AD937B15: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-07-04 22:00:03.723299 :AD937B1D: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-07-04 22:00:03.724886 :AD937B25: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-07-04 22:00:03.726337 :AD937B2D: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-07-04 22:00:03.727820 :AD937B37: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-07-04 22:00:03.748217 :AD937B3F: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-07-04 22:00:03.748965 :AD937B47: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-07-04 22:00:03.749544 :AD937B4F: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-07-04 22:00:03.750056 :AD937B57: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-07-04 22:00:03.752631 :AD937B5F: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-07-04 22:00:03.753653 :AD937B67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.754475 :AD937B68:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.755265 :AD937B69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.755688 :AD937B6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.755695 :AD937B6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.756283 :AD937B6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.756789 :AD937B6D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.757169 :AD937B6E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.811203 :AD937B71: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-07-04 22:00:03.812246 :AD937B79:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.848177*:AD937BC8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003207E9D8 estart=1751637602 eid=16778307 ctx=0x000000077B458BE8 cwh=0x00000000320144E8 cpu=31250 buffg=1436 interb=98304 r=12 rb=98304 w=0 wb=0 2025-07-04 22:00:03.848177*:AD937BC9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003207E9D8 curCtx=0x00000000325DF550 pubTabIdxCnt=0 2025-07-04 22:00:03.848177*:AD937BCA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003207E9D8 2025-07-04 22:00:03.848177*:AD937BCB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003207E9D8 2025-07-04 22:00:03.848177*:AD937BCC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003207E9D8 curCtx=0x00000000325DF550 xsc->flg4=65792 flags=2 2025-07-04 22:00:03.848177*:AD937BCD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003207E9D8 2025-07-04 22:00:03.852882 :AD937BCE: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-07-04 22:00:03.852930 :AD937BD1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.853845 :AD937BD7: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-07-04 22:00:03.854794 :AD937BDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.855301 :AD937BE0: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-07-04 22:00:03.876469 :AD937BE8: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-07-04 22:00:03.876522 :AD937BEB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.877489 :AD937BF1: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-07-04 22:00:03.878382 :AD937BF9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.878897 :AD937BFA: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-07-04 22:00:03.900202 :AD937C02: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-07-04 22:00:03.900254 :AD937C05:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.901173 :AD937C0B: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-07-04 22:00:03.913063 :AD937C13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.913589 :AD937C14: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-07-04 22:00:03.932765 :AD937C1E: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-07-04 22:00:03.932833 :AD937C21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.933977 :AD937C27: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-07-04 22:00:03.935060 :AD937C2F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.935709 :AD937C30: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-07-04 22:00:03.968483 :AD937C44: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-07-04 22:00:03.968538 :AD937C47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.969511 :AD937C4D: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-07-04 22:00:03.970408 :AD937C55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-04 22:00:03.970919 :AD937C56: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-07-04 22:00:03.993379 :AD937C66: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-07-04 22:00:03.993431 :AD937C69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-04 22:00:03.994402 :AD937C6F: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-07-04 22:00:03.994877 :AD937C77: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-07-04 22:00:03.995244 :AD937C7F: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-07-04 22:00:03.995427 :AD937C87: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-07-04 22:00:03.995566 :AD937C8F: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-07-04 22:00:03.995742 :AD937C97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:jslv.c:9203 2025-07-04 22:00:04.000004 :AD937C98: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-07-04 22:00:04.000090 :AD937C9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-04 22:00:04.000182 :AD937CA8: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-07-04 22:00:34.079864 :AD9388CD:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-04 23:59:09.970606 :AD9D3000:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-04 23:59:09.966 2025-07-04 23:59:09.970607 :AD9D3001:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13260 pso_num=31 pso_serial#=109 2025-07-04 23:59:09.970742 :AD9D3002:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13260 (legacy spawn) 2025-07-04 23:59:09.984707 :AD9D3003: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-07-04 23:59:43.225737 :AD9D3BC9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-04 23:59:46.809882 :AD9D3D0D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-04 23:59:46.809 2025-07-04 23:59:46.809884 :AD9D3D0E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7020 pso_num=31 pso_serial#=110 2025-07-04 23:59:46.810039 :AD9D3D0F:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7020 (legacy spawn) 2025-07-04 23:59:46.824333 :AD9D3D1C: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-07-05 00:00:20.074130 :AD9D49A6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-05 06:00:01.226247 :ADB9696D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 06:00:01.221 2025-07-05 06:00:01.226248 :ADB9696E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18312 pso_num=31 pso_serial#=111 2025-07-05 06:00:01.226370 :ADB9696F:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18312 osp_idx=29 osp_ver=620 osp_pg=0 (spawn #353611) 2025-07-05 06:00:01.226371 :ADB96970:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 06:00:01.226371 :ADB96971:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=193 time=375977111 2025-07-05 06:00:01.226371 :ADB96972:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 06:00:01.226372 :ADB96973:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 06:00:01.226372 :ADB96974:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 06:00:01.226373 :ADB96975:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 06:00:01.226373 :ADB96976:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 06:00:01.226965 :ADB9697A: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-07-05 06:00:01.321167 :ADB96C6C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-05 06:00:31.407269 :ADB97F62:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-05 23:59:11.038067 :AE11872C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 23:59:11.029 2025-07-05 23:59:11.038069 :AE11872D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17888 pso_num=31 pso_serial#=112 2025-07-05 23:59:11.038217 :AE11872E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17888 (legacy spawn) 2025-07-05 23:59:11.053346 :AE11872F: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-07-05 23:59:44.214952 :AE1193E2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-05 23:59:53.128293 :AE119749:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 23:59:53.120 2025-07-05 23:59:53.128296 :AE11974A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15364 pso_num=31 pso_serial#=113 2025-07-05 23:59:53.128440 :AE11974B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=15364 (legacy spawn) 2025-07-05 23:59:53.134970 :AE11974C: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-07-05 23:59:53.135179 :AE119751: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-07-05 23:59:53.135988 :AE119756:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-05 23:59:56.040082 :AE119879:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 23:59:56.040 2025-07-05 23:59:56.040084 :AE11987A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9160 pso_num=31 pso_serial#=114 2025-07-05 23:59:56.040219 :AE11987B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9160 (legacy spawn) 2025-07-05 23:59:56.055002 :AE11987C: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-07-06 00:00:29.396570 :AE11A629:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-06 06:00:02.055023 :AE2E882B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 06:00:02.047 2025-07-06 06:00:02.055024 :AE2E882C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15240 pso_num=31 pso_serial#=115 2025-07-06 06:00:02.055173 :AE2E882D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=15240 osp_idx=29 osp_ver=624 osp_pg=0 (spawn #356947) 2025-07-06 06:00:02.055173 :AE2E882E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-06 06:00:02.055174 :AE2E882F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=194 time=462377939 2025-07-06 06:00:02.055175 :AE2E8830:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-07-06 06:00:02.055176 :AE2E8831:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-07-06 06:00:02.055177 :AE2E8832:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-07-06 06:00:02.055178 :AE2E8833:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-06 06:00:02.055178 :AE2E8834:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-06 06:00:02.055835 :AE2E8835: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-07-06 06:00:02.153984 :AE2E8AE5:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-06 06:00:32.215041 :AE2E9A65:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-06 23:59:18.294044 :AE858C16:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 23:59:18.280 2025-07-06 23:59:18.294046 :AE858C17:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17908 pso_num=31 pso_serial#=116 2025-07-06 23:59:18.294193 :AE858C18:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17908 (legacy spawn) 2025-07-06 23:59:18.301007 :AE858C19: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-07-06 23:59:18.301191 :AE858C1E: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-07-06 23:59:18.302020 :AE858C23:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-06 23:59:18.663314 :AE858C3C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 23:59:18.655 2025-07-06 23:59:18.663316 :AE858C3D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17064 pso_num=31 pso_serial#=117 2025-07-06 23:59:18.663464 :AE858C3E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17064 (legacy spawn) 2025-07-06 23:59:18.670498 :AE858C3F: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-07-06 23:59:18.670697 :AE858C46: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-07-06 23:59:18.671658 :AE858C49:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-06 23:59:19.720993 :AE858C7B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 23:59:19.718 2025-07-06 23:59:19.720995 :AE858C7C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6696 pso_num=31 pso_serial#=118 2025-07-06 23:59:19.721134 :AE858C7D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=6696 (legacy spawn) 2025-07-06 23:59:19.735631 :AE858C7E: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-07-06 23:59:23.027308 :AE858DE3: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-07-06 23:59:23.027485 :AE858DE8: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-07-06 23:59:23.028700 :AE858DED:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-06 23:59:23.294589 :AE858DF0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 23:59:23.280 2025-07-06 23:59:23.294591 :AE858DF1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16980 pso_num=31 pso_serial#=119 2025-07-06 23:59:23.294787 :AE858DF2:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16980 (legacy spawn) 2025-07-06 23:59:23.310719 :AE858DF3: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-07-06 23:59:56.440165 :AE859A5A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-06 23:59:56.482635 :AE859A5D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 23:59:56.468 2025-07-06 23:59:56.482637 :AE859A5E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14320 pso_num=31 pso_serial#=120 2025-07-06 23:59:56.482816 :AE859A5F:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14320 (legacy spawn) 2025-07-06 23:59:56.497152 :AE859A60: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-07-06 23:59:59.764379 :AE859BA6: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-07-06 23:59:59.764559 :AE859BAF: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-07-06 23:59:59.765718 :AE859BB0:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-07 00:00:00.027891 :AE859C13:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-07 00:00:00.019 2025-07-07 00:00:00.027893 :AE859C14:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13924 pso_num=31 pso_serial#=121 2025-07-07 00:00:00.028093 :AE859C15:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13924 (legacy spawn) 2025-07-07 00:00:00.042805 :AE859C16: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-07-07 00:06:19.993502 :AE8625E7: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-07-07 00:06:19.993681 :AE8625EC: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-07-07 00:06:19.994895 :AE8625F1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-07 22:00:00.761280 :AEEF60C6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-07 22:00:00.756 2025-07-07 22:00:00.761281 :AEEF60C7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17388 pso_num=31 pso_serial#=122 2025-07-07 22:00:00.761416 :AEEF60C8:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17388 osp_idx=29 osp_ver=631 osp_pg=0 (spawn #362514) 2025-07-07 22:00:00.761417 :AEEF60C9:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-07 22:00:00.761418 :AEEF60CA:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=195 time=606376642 2025-07-07 22:00:00.761418 :AEEF60CB:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-07-07 22:00:00.761418 :AEEF60CC:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-07-07 22:00:00.761419 :AEEF60CD:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-07-07 22:00:00.761419 :AEEF60CE:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-07 22:00:00.761420 :AEEF60CF:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-07 22:00:00.762100 :AEEF60D3: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-07-07 22:00:00.865982 :AEEF6357:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-07 22:00:00.871587 :AEEF6359: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-07-07 22:00:00.871626 :AEEF635C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:jslv.c:9203 2025-07-07 22:00:00.874647 :AEEF6362:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:jslv.c:9203 2025-07-07 22:00:00.878047 :AEEF6363: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-07-07 22:00:00.878131 :AEEF6366:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-07 22:00:00.878220 :AEEF6373: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 17388, J005)