2026-10-28T18:00:12Z pipeline 4011 removed
2026-10-28T18:24:43Z speed: asyncredo=1 capture_s=1440 peak_rss_gb=25.68 transactions=7786142 inserts=24418298 updates=19160156 deletes=0
2026-10-28T18:26:37Z rerun: Repstance 5.2.0285, asyncredo=1, redo to generate 48.5 GB
2026-10-28T18:26:37Z rerun: archived log retention 1 h to purge the old logs
2026-10-28T18:30:09Z rerun: retention back to 12 h, archived logs on disk: 0 GB
2026-10-28T18:30:09Z SOE schema exists
2026-10-28T19:03:38Z SOE tables are on the target
2026-10-28T19:03:38Z result soe_il: {"tables":11,"rows":223455941,"seconds":1988,"rows_per_h":404648585.31187123,"capture_seconds":978,"capture_rows_per_h":822537206.1349694}
2026-10-28T19:03:38Z rerun: initial load done in 2009 s, pipeline 4012
2026-10-28T19:03:43Z rerun: capture and apply 4012 stopped
2026-10-28T19:03:44Z rerun: generating 48.5 GB of redo with 128 users (start SCN 187498485)
2026-10-28T19:52:39Z rerun: generated 49.234 GB, SCN 187498485 .. 204035618
2026-10-28T19:52:39Z rerun: source customers 15963970
2026-10-28T19:52:40Z FAILED: the capture 4012 is not stopped
2026-10-28T19:52:40Z FAILED: the capture 4012 is not stopped
2026-10-28T19:52:40Z result redo_generated: {"redo_gb":49.234,"start_scn":187498485,"end_scn":204035618}
2026-10-28T19:52:40Z rerun: finished
2026-10-28T19:58:15Z capture phase: the capture 4012 starts at SCN 187498454, end SCN 204035618, the apply stays stopped
2026-10-28T20:20:25Z capture phase:  RUN (In Process), Waiting for Transactions; Processing the Transactions : 00:21:48.745; Waiting for Transactions : 00:00:06.294; Average Speed (ops) 	: 32977.188;
2026-10-28T20:20:30Z result capture_only: {"start_scn":187498454,"end_scn":204035618,"capture_s":1330,"transactions":7715850,"inserts":24182298,"updates":18976452,"deletes":0,"changes":43158750,"peak_rss_gb":14.16}
2026-10-28T20:20:42Z apply phase: the apply 4012 starts, end SCN 204035618, the capture stays stopped
2026-10-28T20:33:52Z apply phase:  RUN (In Process), Waiting for Transactions; Processing the Transactions : 00:09:02.451; Waiting for Transactions : 00:04:07.902; Average Speed (ops) 	: 79562.461;
2026-10-28T20:34:13Z result apply_only: {"end_scn":204035618,"apply_s":780,"transactions":147,"inserts":24182298,"updates":18976452,"deletes":0,"changes":43158750,"table_differences":0,"gate":"pass"}
2026-10-28T20:49:21Z T3: using the pipeline 4012 of the earlier test, the SOE tables are on the target already
2026-10-28T20:50:02Z T3: the target idle for 5 minutes (its CPU without load)
2026-10-28T20:55:02Z T3: step 25 percent, 32 users, 3 min
2026-10-28T20:58:20Z result t3: {"step_percent":25,"users":32,"samples":63,"lag_avg_ms":7924,"lag_p95_ms":10631,"lag_max_ms":13345,"lag_first_third_ms":7635,"lag_last_third_ms":8028,"lag_bounded":true}
2026-10-28T20:58:20Z T3: step 50 percent, 64 users, 3 min
2026-10-28T21:01:37Z result t3: {"step_percent":50,"users":64,"samples":47,"lag_avg_ms":10770,"lag_p95_ms":14903,"lag_max_ms":18800,"lag_first_third_ms":10184,"lag_last_third_ms":10261,"lag_bounded":true}
2026-10-28T21:01:37Z T3: step 75 percent, 96 users, 3 min
2026-10-28T21:04:55Z result t3: {"step_percent":75,"users":96,"samples":37,"lag_avg_ms":17790,"lag_p95_ms":26013,"lag_max_ms":30306,"lag_first_third_ms":17125,"lag_last_third_ms":17444,"lag_bounded":true}
2026-10-28T21:04:55Z T3: step 100 percent, 128 users, 3 min
2026-10-28T21:08:13Z result t3: {"step_percent":100,"users":128,"samples":21,"lag_avg_ms":32081,"lag_p95_ms":47753,"lag_max_ms":59605,"lag_first_third_ms":21659,"lag_last_third_ms":38268,"lag_bounded":false}
2026-10-28T21:08:13Z T3: baseline pass, 64 users, 3 min, the capture stopped
2026-10-28T21:16:08Z result t3_cpu: {"step_percent":25,"source_cpu_percent":null,"target_cpu_percent":null}
2026-10-28T21:16:09Z result t3_cpu: {"step_percent":50,"source_cpu_percent":null,"target_cpu_percent":null}
2026-10-28T21:16:10Z result t3_cpu: {"step_percent":75,"source_cpu_percent":null,"target_cpu_percent":null}
2026-10-28T21:16:11Z result t3_cpu: {"step_percent":100,"source_cpu_percent":null,"target_cpu_percent":null}
2026-10-28T21:16:13Z result t3_cpu_idle_and_baseline: {"source_without_replication_50_percent":null,"target_idle":null}
2026-10-28T21:16:13Z T3: CloudWatch gave no CPU points (see cw_errors.log; the role needs cloudwatch:GetMetricStatistics); run tools/t3_cpu.sh later with the AWS credentials
2026-10-28T21:16:13Z result t3_impact: {"step_percent":50,"impact_percentage_points":null}
2026-10-28T21:16:39Z result t3_gate: {"table_differences":0,"gate":"pass"}
2026-10-28T21:18:19Z result t3_cpu: {"step_percent":25,"source_cpu_percent":{"avg":49.95,"max":59.50,"points":3},"target_cpu_percent":{"avg":4.90,"max":7.00,"points":3}}
2026-10-28T21:18:21Z result t3_cpu: {"step_percent":50,"source_cpu_percent":{"avg":55.27,"max":63.95,"points":3},"target_cpu_percent":{"avg":5.21,"max":5.72,"points":3}}
2026-10-28T21:18:23Z result t3_cpu: {"step_percent":75,"source_cpu_percent":{"avg":56.11,"max":65.28,"points":3},"target_cpu_percent":{"avg":5.02,"max":6.22,"points":3}}
2026-10-28T21:18:25Z result t3_cpu: {"step_percent":100,"source_cpu_percent":{"avg":55.65,"max":60.63,"points":3},"target_cpu_percent":{"avg":4.59,"max":5.79,"points":3}}
2026-10-28T21:18:29Z result t3_cpu_idle_and_baseline: {"source_without_replication_50_percent":{"avg":50.21,"max":64.04,"points":3},"target_idle":{"avg":1.65,"max":1.92,"points":5}}
2026-10-28T21:18:29Z result t3_impact: {"step_percent":50,"impact_percentage_points":{"source_avg":5.06,"source_max":13.74,"target_avg":3.56,"target_max":4.06}}
