Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607060008\orcl_j002_14696_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:36806M/63366M, Ph+PgF:43412M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 14696, image: ORACLE.EXE (J002) *** 2025-06-07 06:00:08.834 *** SESSION ID:(225.33403) 2025-06-07 06:00:08.834 *** 2025-06-07 06:00:08.834 Process diagnostic dump for ORACLE.EXE (J002), OS id=14696, pid: 28, proc_ser: 66, sid: 225, sess_ser: 33403 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14696 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.163883 sec, exc=0.163883 sec, total=0.163883 sec wait times: max=0.500000 sec, heur=6.769459 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=13 seq_num=14 snap_id=1 wait times: snap=0.499978 sec, exc=0.499978 sec, total=0.499978 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.505101 sec, exc=0.505101 sec, total=0.505101 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=10 seq_num=11 snap_id=1 wait times: snap=0.500029 sec, exc=0.500029 sec, total=0.500029 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000012 sec of elapsed time 6: 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.502120 sec, exc=0.502120 sec, total=0.502120 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time Sampled Session History of session 225 serial 33403 --------------------------------------------------- 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, 06:00:02 - 06:00:08] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 06:00:08.834 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 14696, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 01:56:59.169296 :A1502351:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:56:59.154 2025-06-07 01:56:59.169298 :A1502352:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16156 pso_num=28 pso_serial#=47 2025-06-07 01:56:59.169437 :A1502353:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16156 (legacy spawn) 2025-06-07 01:56:59.176045 :A1502354:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:56:59.176221 :A1502359:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:56:59.177057 :A150235E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:56:59.185997 :A1502361:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:56:59.186 2025-06-07 01:56:59.185999 :A1502362:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15692 pso_num=28 pso_serial#=48 2025-06-07 01:56:59.186140 :A1502363:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15692 (legacy spawn) 2025-06-07 01:56:59.192648 :A1502364:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:56:59.192824 :A150236C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:56:59.193694 :A150236E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:57:02.333883 :A150248A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:57:02.321 2025-06-07 01:57:02.333884 :A150248B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8928 pso_num=28 pso_serial#=49 2025-06-07 01:57:02.334028 :A150248C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8928 (legacy spawn) 2025-06-07 01:57:02.340534 :A150248D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:57:02.340711 :A1502492:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:57:02.341519 :A1502497:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:57:02.350116 :A150249A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:57:02.336 2025-06-07 01:57:02.350118 :A150249B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13816 pso_num=28 pso_serial#=50 2025-06-07 01:57:02.350254 :A150249C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13816 (legacy spawn) 2025-06-07 01:57:02.356628 :A150249D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:57:02.356794 :A15024A6:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:57:02.357631 :A15024A7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:57:05.545955 :A1502594:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:57:05.538 2025-06-07 01:57:05.545958 :A1502595:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16056 pso_num=28 pso_serial#=51 2025-06-07 01:57:05.546102 :A1502596:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16056 (legacy spawn) 2025-06-07 01:57:05.552587 :A1502597:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:57:05.552766 :A150259C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:57:05.553620 :A15025A1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:57:06.222181 :A15025DF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:57:06.209 2025-06-07 01:57:06.222183 :A15025E0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16092 pso_num=28 pso_serial#=52 2025-06-07 01:57:06.222319 :A15025E1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16092 (legacy spawn) 2025-06-07 01:57:06.228759 :A15025E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:57:06.228937 :A15025EB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:57:06.229738 :A15025EC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:58:07.713753 :A1503A34:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:58:07.705 2025-06-07 01:58:07.713755 :A1503A35:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14972 pso_num=28 pso_serial#=53 2025-06-07 01:58:07.713896 :A1503A36:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14972 (legacy spawn) 2025-06-07 01:58:07.720443 :A1503A37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:58:07.720620 :A1503A3E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:58:07.721503 :A1503A58:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:58:12.158856 :A1503BBE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:58:12.156 2025-06-07 01:58:12.158859 :A1503BBF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12756 pso_num=28 pso_serial#=54 2025-06-07 01:58:12.158992 :A1503BC0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12756 (legacy spawn) 2025-06-07 01:58:12.165439 :A1503BC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:58:12.165616 :A1503BCA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:58:12.166408 :A1503BCB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:58:12.195735 :A1503BCE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:58:12.187 2025-06-07 01:58:12.195737 :A1503BCF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16140 pso_num=28 pso_serial#=55 2025-06-07 01:58:12.195910 :A1503BD0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16140 (legacy spawn) 2025-06-07 01:58:12.202410 :A1503BD1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:58:12.202581 :A1503BDA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:58:12.203446 :A1503BDB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:58:14.587685 :A1503C8B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:58:14.579 2025-06-07 01:58:14.587687 :A1503C8C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16128 pso_num=28 pso_serial#=56 2025-06-07 01:58:14.587835 :A1503C8D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16128 (legacy spawn) 2025-06-07 01:58:14.594375 :A1503C8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:58:14.594548 :A1503C93:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:58:14.595401 :A1503C98:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:58:15.234456 :A1503CD6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:58:15.221 2025-06-07 01:58:15.234458 :A1503CD7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14876 pso_num=28 pso_serial#=57 2025-06-07 01:58:15.234597 :A1503CD8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14876 (legacy spawn) 2025-06-07 01:58:15.241087 :A1503CD9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:58:15.241261 :A1503CE2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:58:15.242074 :A1503CE3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:58:15.250682 :A1503CE6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:58:15.237 2025-06-07 01:58:15.250683 :A1503CE7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12136 pso_num=28 pso_serial#=58 2025-06-07 01:58:15.250824 :A1503CE8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12136 (legacy spawn) 2025-06-07 01:58:15.257287 :A1503CE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:58:15.257453 :A1503CF2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:58:15.258257 :A1503CF3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:59:09.897663 :A1504F29:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:59:09.884 2025-06-07 01:59:09.897665 :A1504F2A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15596 pso_num=28 pso_serial#=59 2025-06-07 01:59:09.897803 :A1504F2B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15596 (legacy spawn) 2025-06-07 01:59:09.904382 :A1504F2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:59:09.904559 :A1504F31:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:59:09.905357 :A1504F36:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:59:22.613438 :A150535C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:59:22.604 2025-06-07 01:59:22.613440 :A150535D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16060 pso_num=28 pso_serial#=60 2025-06-07 01:59:22.613580 :A150535E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16060 (legacy spawn) 2025-06-07 01:59:22.619983 :A150535F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:59:22.620493 :A1505364:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:59:22.621326 :A1505369:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:59:24.714149 :A1505407:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:59:24.699 2025-06-07 01:59:24.714151 :A1505408:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16252 pso_num=28 pso_serial#=61 2025-06-07 01:59:24.714301 :A1505409:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16252 (legacy spawn) 2025-06-07 01:59:24.720810 :A150540A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:59:24.720840 :A150540D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:59:24.720992 :A1505410:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:59:24.721792 :A1505421:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 01:59:24.737559 :A1505424:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 01:59:24.731 2025-06-07 01:59:24.737561 :A1505425:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13400 pso_num=28 pso_serial#=62 2025-06-07 01:59:24.737705 :A1505426:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13400 (legacy spawn) 2025-06-07 01:59:24.744169 :A1505427:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 01:59:24.744344 :A1505430:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 01:59:24.745171 :A1505431:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 02:00:00.022194 :A1506036:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 02:00:00.011 2025-06-07 02:00:00.022197 :A1506037:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15872 pso_num=28 pso_serial#=63 2025-06-07 02:00:00.022340 :A1506038:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15872 osp_idx=26 osp_ver=180396 osp_pg=0 (spawn #259688) 2025-06-07 02:00:00.022341 :A1506039:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 02:00:00.022341 :A150603A:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=148 time=2237358657 2025-06-07 02:00:00.022341 :A150603B:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 02:00:00.022342 :A150603C:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 02:00:00.022342 :A150603D:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 02:00:00.022343 :A150603E:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 02:00:00.022343 :A150603F:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 02:00:00.022882 :A1506040:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.022926 :A1506047:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 02:00:00.022997 :A150605B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.026988 :A150606B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.078596 :A1506075:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.081193 :A150607D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.082645 :A1506085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.083543 :A150608D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.083565 :A1506090:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.083605 :A150609B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 02:00:00.084298 :A15060A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.084313 :A15060A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.084337 :A15060B5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 02:00:00.085188 :A15060BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.085202 :A15060C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-07 02:00:00.085228 :A15060CE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 02:00:00.085288 :A15060D8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 02:00:00.086408 :A15060DA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 03:03:02.629047 :A1557AAA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 03:03:02.618 2025-06-07 03:03:02.629050 :A1557AAB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14720 pso_num=28 pso_serial#=64 2025-06-07 03:03:02.629186 :A1557AAC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14720 (legacy spawn) 2025-06-07 03:03:02.635684 :A1557AAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 03:03:02.695213 :A1557ABC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 03:03:02.695979 :A1557AC4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 04:17:30.776173 :A15B7D3F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 04:17:30.774 2025-06-07 04:17:30.776174 :A15B7D40:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15880 pso_num=28 pso_serial#=65 2025-06-07 04:17:30.776315 :A15B7D41:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15880 (legacy spawn) 2025-06-07 04:17:30.782997 :A15B7D45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 04:17:30.783159 :A15B7D4E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 04:17:30.783951 :A15B7D4F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 06:00:02.044659 :A163A162:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 06:00:02.044 2025-06-07 06:00:02.044661 :A163A163:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14696 pso_num=28 pso_serial#=66 2025-06-07 06:00:02.044807 :A163A164:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14696 osp_idx=26 osp_ver=180399 osp_pg=0 (spawn #260246) 2025-06-07 06:00:02.044808 :A163A165:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 06:00:02.044808 :A163A166:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=149 time=2251760688 2025-06-07 06:00:02.044809 :A163A167:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 06:00:02.044809 :A163A168:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 06:00:02.044809 :A163A169:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 06:00:02.044810 :A163A16A:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 06:00:02.044810 :A163A16B:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 06:00:02.045335 :A163A175:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.050252 :A163A192:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.050293 :A163A195:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-06-07 06:00:02.054847 :A163A1C2:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-07 06:00:02.055454 :A163A1C3:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 81f8110000000000 2025-06-07 06:00:02.057084 :A163A1ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.057162 :A163A202:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 06:00:02.057174 :A163A208:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-06-07 06:00:02.057178 :A163A209:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.057208 :A163A214:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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:02.057894 :A163A229:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 227626984) 2025-06-07 06:00:02.057899 :A163A22A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.057923 :A163A235:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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:02.057933 :A163A242:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65564 1) 2025-06-07 06:00:02.057936 :A163A243:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.057972 :A163A24F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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:02.057976 :A163A256:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65564) 2025-06-07 06:00:02.057979 :A163A25C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.058024 :A163A27B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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:02.058744 :A163A317:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 06:00:02.058772 :A163A31F:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-07 06:00:02.059777 :A163A32A:db_trace:kcc.c@13684:kccwbp(): [10021:28:225:1] 0115000000000000 0115000000000000 82f8110000000000 2025-06-07 06:00:02.060321 :A163A32B:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 227626984) 2025-06-07 06:00:02.060332 :A163A32C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.060370 :A163A339:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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:02.068917 :A163A39A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:jslv.c:9203 2025-06-07 06:00:02.070784 :A163A39B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.070841 :A163A39E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-07 06:00:02.070945 :A163A3AB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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:02.169406 :A163A49B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 14696, J002)