refactor(编号): 链路延迟分段 L1~L11 → 段名(文档中文名 / C 日志 ASCII 短码,批次 3b)

这族是延迟测试的分段号(L1 SPI 字节命令 … L11 uiTask 节拍)。特殊之处:**号直接打进
串口日志字符串**,而 C 字符串必须纯 ASCII(AGENTS 铁律)——所以两份落地面用两套名:

  文档(人读):SPI 字节命令 / SPI DMA / 发送分块握手 / 芯片→线上+PC / 队列往返 /
                请求→首字节 / 整帧 3209B 往返 / 生产路径帧周期 / 连接/重监听 / 日志税 / uiTask 节拍
  C 日志(ASCII):SPI_CMD / SPI_DMA / CHUNK_WAIT / TO_PC / QUEUE_RT / ECHO_RT /
                FRAME_RT / PROD_CYCLE / RELISTEN / LOGTAX / UI_LOOP

改动:`链路延迟测试说明.md` 84 行(分段定义表的「段」列删掉、名称列即段名;正文与
基线表同步);`test/board/net_latency_test.c` 61 行(日志串与注释,行数不变、逐行替换)。
标识符一律不碰:`LAT_P13_OP_L5_RECV` / `P13_ST_L5_SEND` 这类宏与枚举仍在(它们是代码符号,
不是给人读的段号)——7 处已核完整。

一次事故:第一版把该 .c 写坏了(整篇被同名表格行覆盖),已 `git checkout` 回滚,改成
「只改命中行 + 行数断言」重做,本次 61 行、1313→1313 行不变。

验证:文档与 C 两侧段号残留为 0;doc-links / mojibake / 新编号门禁 / git diff --check 全绿。
该文件属板级测试套件(产品构建不编译它),本次只动注释与字符串,不改任何逻辑。
This commit is contained in:
2026-10-28 13:19:04 +08:00
parent 0f0158508a
commit b9f5df5d28
2 changed files with 144 additions and 144 deletions
+83 -83
View File
@@ -100,7 +100,7 @@ sequenceDiagram
| 区制 | 实测 | 主导成本 | 适用 |
|---|---|---|---|
| **小帧交互**(≈1 chunk) | 3209B → 66ms(客户端关延迟 ACK 后 → 20.7ms) | 固定尾批惩罚 ~52ms(已客户端侧解决,§7.3/§7.4) | LCDMEM 整帧轮询 |
| **批量流式**(多满块) | 4KB/7.8ms、64KB/125ms ≈ 2ms/KB | SPI DMA(L2 = 1.61ms/KB) | 大文件 / FTP 传数据 |
| **批量流式**(多满块) | 4KB/7.8ms、64KB/125ms ≈ 2ms/KB | SPI DMA(SPI DMA = 1.61ms/KB) | 大文件 / FTP 传数据 |
两条独立的优化线:小帧交互的尾批已定性并解决;批量流式仍受 SPI DMA 约束。
@@ -108,20 +108,20 @@ sequenceDiagram
## 3. 分段定义与测量点
| 段 | 名称 | 测什么 | 测点 / 方法 | 基线(实测,详见 §6) | 超标指向 |
|---|---|---|---|---|---|
| **L1** | SPI 字节命令 | 一条小 SPI 事务(GET_IC_VER / CHECK_EXIST / GLOB_INT,4~7B)端到端 | MCU,DWT µs,Phase 12,N=100/命令 | 10 / 15 / 15µs | SPI 走线/PCB/分频/DMA 配置 |
| **L2** | SPI DMA | `ch395f_read_recv_buf(N)` 完整 DMA 事务(含 CS 切换),N ∈ {4, 64, 1024, 2048} | MCU,DWT µs,Phase 12,N=100/档 | 20 / 114 / **1611** / 3208µs(斜率 1.56µs/B + 固定 14µs) | SPI2/DMA/总线异常 |
| **L3** | 发送分块握手 | `write_send_buf(1KB)` → `SENBUF_FREE` 的等待时长(= `net_send_locked` 每 chunk 的真实等待) | MCU(netTask 上下文),Phase 13 | **219µs**/chunk | 芯片发送路径 / 重传(D1) / 链路(D7) |
| **L4** | 芯片→线上+PC | 数据交入芯片协议栈 → PC 首见字节 / 收齐全帧(peer 逐 `recv()` 批次打时间戳) | PC(peer),Phase 13/14 | 首批 8.6ms;**段间间隙 ≥200ms = 重传**(P0-a 后粒度;旧固件 500ms) | D1/D4/D7("网络延迟"直接证据) |
| **L5** | 队列往返 | 调用方视角 `net_recv(DONTWAIT)` / `net_send` 全程 = 入队 + netTask 调度 + 处理 + 通知 | MCU(调用方计时),Phase 13,**两档**:静默基线 + 压力档(并发 FTP 流量) | 静默:收 1223µs / 发 988µs(2026-09-22 重锁,P0-b 后;L3 对 DONTWAIT 路径逐位不变,重验留待 Phase 13 下次窗口) | D3(头阻塞,已两阶段修复) / D5 调度 |
| **L6** | 请求→首字节 | peer 发 10B 请求 → MCU 收齐并回显(服务端视角);与 PC 侧对照,差值 = 线路 + PC 栈 | MCU + PC 双侧,Phase 13 | MCU 986µs / PC 3.9ms | MCU 收路径/调度 |
| **L7** | 整帧 3209B 往返 | LCDMEM 全帧:PC 发 → 收齐(含 L3+L4) | PC 为主,Phase 13 | 61.0ms(M2);**spike(≥500ms)=0** | D1+D2+D4 综合 |
| **L8** | 生产路径帧周期 | **真实 uiTask + 真实 `ui_net_sink`**(:7003)完整帧周期:请求拾取(10ms 拍)+ 队列 + 发送 + L4 + peer 侧编码 | MCU 探针 + PC,Phase 14 | 真机 61.6ms / 模拟器 6.8ms(含 peer 100ms 节拍) | 全段(用户真实体感) |
| **L9** | 连接/重监听 | connect→ESTABLISHED;close→relisten 成功 | MCU(net_poll 观测点),Phase 14 | relisten **89ms**(D10 修复后) | 握手/队列;**≥500ms = D10 回归** |
| **L10** | 日志税 | 一行 60 字符 `printf`(现 fputc 逐字节 @115200)耗时 | MCU,Phase 13 附带 | **4402µs/行** | D6(per-recv 日志已删 2026-09-22;非阻塞日志仍待办) |
| **L11** | uiTask 节拍 | 生产循环实测周期(`osDelay(10)` 之外多花多少 = LVGL 渲染 + 渲染中排空) | MCU 探针,Phase 14 | **10986µs**(渲染仅 ~1ms/帧) | D5/D9(实测均非瓶颈) |
| **Δ** | CH395F 净贡献 | `L8(真机) − L8(模拟器)`,同一 peer 交替跑 | PC,Phase 14 | **54.8ms**(阈值 ≤80ms) | 直接回答"网络延迟"占多少 |
| 名称 | 测什么 | 测点 / 方法 | 基线(实测,详见 §6) | 超标指向 |
|---|---|---|---|---|
| SPI 字节命令 | 一条小 SPI 事务(GET_IC_VER / CHECK_EXIST / GLOB_INT,4~7B)端到端 | MCU,DWT µs,Phase 12,N=100/命令 | 10 / 15 / 15µs | SPI 走线/PCB/分频/DMA 配置 |
| SPI DMA | `ch395f_read_recv_buf(N)` 完整 DMA 事务(含 CS 切换),N ∈ {4, 64, 1024, 2048} | MCU,DWT µs,Phase 12,N=100/档 | 20 / 114 / **1611** / 3208µs(斜率 1.56µs/B + 固定 14µs) | SPI2/DMA/总线异常 |
| 发送分块握手 | `write_send_buf(1KB)` → `SENBUF_FREE` 的等待时长(= `net_send_locked` 每 chunk 的真实等待) | MCU(netTask 上下文),Phase 13 | **219µs**/chunk | 芯片发送路径 / 重传(D1) / 链路(D7) |
| 芯片→线上+PC | 数据交入芯片协议栈 → PC 首见字节 / 收齐全帧(peer 逐 `recv()` 批次打时间戳) | PC(peer),Phase 13/14 | 首批 8.6ms;**段间间隙 ≥200ms = 重传**(P0-a 后粒度;旧固件 500ms) | D1/D4/D7("网络延迟"直接证据) |
| 队列往返 | 调用方视角 `net_recv(DONTWAIT)` / `net_send` 全程 = 入队 + netTask 调度 + 处理 + 通知 | MCU(调用方计时),Phase 13,**两档**:静默基线 + 压力档(并发 FTP 流量) | 静默:收 1223µs / 发 988µs(2026-09-22 重锁,P0-b 后;发送分块握手对 DONTWAIT 路径逐位不变,重验留待 Phase 13 下次窗口) | D3(头阻塞,已两阶段修复) / D5 调度 |
| 请求→首字节 | peer 发 10B 请求 → MCU 收齐并回显(服务端视角);与 PC 侧对照,差值 = 线路 + PC 栈 | MCU + PC 双侧,Phase 13 | MCU 986µs / PC 3.9ms | MCU 收路径/调度 |
| 整帧 3209B 往返 | LCDMEM 全帧:PC 发 → 收齐(含发送分块握手+芯片→线上+PC) | PC 为主,Phase 13 | 61.0ms(M2);**spike(≥500ms)=0** | D1+D2+D4 综合 |
| 生产路径帧周期 | **真实 uiTask + 真实 `ui_net_sink`**(:7003)完整帧周期:请求拾取(10ms 拍)+ 队列 + 发送 + 芯片→线上+PC + peer 侧编码 | MCU 探针 + PC,Phase 14 | 真机 61.6ms / 模拟器 6.8ms(含 peer 100ms 节拍) | 全段(用户真实体感) |
| 连接/重监听 | connect→ESTABLISHED;close→relisten 成功 | MCU(net_poll 观测点),Phase 14 | relisten **89ms**(D10 修复后) | 握手/队列;**≥500ms = D10 回归** |
| 日志税 | 一行 60 字符 `printf`(现 fputc 逐字节 @115200)耗时 | MCU,Phase 13 附带 | **4402µs/行** | D6(per-recv 日志已删 2026-09-22;非阻塞日志仍待办) |
| uiTask 节拍 | 生产循环实测周期(`osDelay(10)` 之外多花多少 = LVGL 渲染 + 渲染中排空) | MCU 探针,Phase 14 | **10986µs**(渲染仅 ~1ms/帧) | D5/D9(实测均非瓶颈) |
| CH395F 净贡献 | `生产路径帧周期(真机) − 生产路径帧周期(模拟器)`,同一 peer 交替跑 | PC,Phase 14 | **54.8ms**(阈值 ≤80ms) | 直接回答"网络延迟"占多少 |
---
@@ -140,9 +140,9 @@ sequenceDiagram
### 4.2 阶段编号与构建配置(沿用 `ch395f_test.h` 互斥体系)
```
ENABLE_PHASE12_TESTS [硬] SPI 时延基线(L1/L2)—— testTask 独占 CH395F,**无需 PC**
ENABLE_PHASE13_TESTS [网] 芯片+队列时延(L3~L7/L10)—— 需 latency_peer.py --mode net(:8080)
ENABLE_PHASE14_TESTS [网] 生产路径时延(L8~L11 + Δ)—— 真实 uiTask/:7003,peer --mode remo
ENABLE_PHASE12_TESTS [硬] SPI 时延基线(SPI 字节命令/SPI DMA)—— testTask 独占 CH395F,**无需 PC**
ENABLE_PHASE13_TESTS [网] 芯片+队列时延(发送分块握手~整帧往返/日志税)—— 需 latency_peer.py --mode net(:8080)
ENABLE_PHASE14_TESTS [网] 生产路径时延(生产路径帧周期~uiTask 节拍 + Δ)—— 真实 uiTask/:7003,peer --mode remo
```
- Phase 12 归 HW 组,13/14 归 NET 组;13 与 14 可同启(13 用 testTask 私有监听 :8080,14 用生产 :7003,Socket 不冲突);
@@ -157,7 +157,7 @@ ENABLE_PHASE14_TESTS [网] 生产路径时延(L8~L11 + Δ)—— 真实 uiT
`CoreDebug->DEMCR | TRCENA` + `DWT->CYCCNT=0` + `DWT->CTRL | CYCCNTENA`;
- 分辨率 1/168MHz ≈ 5.95ns;换算用 `SystemCoreClock`(不写死频率);
- **32 位回绕 = 25.17ms**:M1 各段(<5ms)与 M2 调用方侧测量(单次 <25ms)用单次读数差;
跨 25ms 的段(L4/L8 整帧与重传尖峰)由 PC 端 peer 计时,或 MCU 侧**累积采样**
跨 25ms 的段(芯片→线上+PC/生产路径帧周期整帧与重传尖峰)由 PC 端 peer 计时,或 MCU 侧**累积采样**
(等待循环中每 ~10ms 采样计数差并累加,`lat_mark_us`);
- 每次运行先打一行校准线 `[LAT] cal core_clk=...`,台架可用逻辑分析仪核对时基正确性。
@@ -199,11 +199,11 @@ DTU-RemoteLCD 客户端;输出每帧 `req2first / total / recv 批次到达时
| 操作 | 值 | 语义 | peer 侧动作 |
|---|---|---|---|
| HELLO | `5A A5 58 01` | 会话起始 | 连接后立即发送;MCU 识别后重置会话状态机 |
| L5_RECV | `0x01` | 对端灌入 | 20×100B,**50ms 间隔**(≈1KB/s,低于 MCU 抽干速率);测非阻塞收的队列往返 |
| L5_SEND | `0x02` | MCU 连发 | 收 2000B(20×100B 阻塞发) |
| L6 | `0x03` | 小请求/回显 | 发 10B 模式请求 → 校验 MCU 原样回显;逐样本重复 |
| L7 | `0x04` | 全帧读取 | 发 9B LCDMEM 请求 → 收 3209B 全帧(4 批);逐样本重复 |
| L3 | `0x05` | 4KB flood | 排空 16×4096B(=64KB);MCU 侧 chunk 等待由探针测 |
| 队列往返_RECV | `0x01` | 对端灌入 | 20×100B,**50ms 间隔**(≈1KB/s,低于 MCU 抽干速率);测非阻塞收的队列往返 |
| 队列往返_SEND | `0x02` | MCU 连发 | 收 2000B(20×100B 阻塞发) |
| 请求→首字节 | `0x03` | 小请求/回显 | 发 10B 模式请求 → 校验 MCU 原样回显;逐样本重复 |
| 整帧 3209B 往返 | `0x04` | 全帧读取 | 发 9B LCDMEM 请求 → 收 3209B 全帧(4 批);逐样本重复 |
| 发送分块握手 | `0x05` | 4KB flood | 排空 16×4096B(=64KB);MCU 侧 chunk 等待由探针测 |
| DONE | `0xFF` | 会话结束 | 打印汇总并退出(exit 0 = PASS) |
- **帧格式严格照生产 RemoDispBus**(`ui_net_sink.c` `remo_handle`/`remo_send`):
@@ -270,8 +270,8 @@ DTU-RemoteLCD 客户端;输出每帧 `req2first / total / recv 批次到达时
# Forbidden: HardFault / assert / [ERR] / [FAIL] / verdict=FAIL
```
- **会话顺序**:HELLO → L5_RECV → L5_SEND → L3 → L10 → L6 → L7 → DONE。
易失步的 RX 协议段(L6/L7)置于末位,避免失步污染前置段;L6/L7 样本数 10,L3 = 16×4KB;
- **会话顺序**:HELLO → 队列往返_RECV → 队列往返_SEND → 发送分块握手 → 日志税 → 请求→首字节 → 整帧 3209B 往返 → DONE。
易失步的 RX 协议段(请求→首字节/整帧 3209B 往返)置于末位,避免失步污染前置段;请求→首字节/整帧 3209B 往返样本数 10,发送分块握手 = 16×4KB;
- **abort 后自动进"重连等待态"**(120s):DONTWAIT 轮询 scan `5A A5 58 01` → 命中即
`p13_reinit()` + 重启会话;期间周期自愈(10s 无 peer 且状态仍 LISTENING → 重发芯片重监听),
**干净完成后停止自愈**(否则每 10s 拆建监听器,日志永不静默、台架无法判空闲);
@@ -348,12 +348,12 @@ DMA 斜率实测 1.56µs/B(≈5.25 Mbit/s,与 SPI2 分频 8 @42MHz APB1 设
| 段(seg) | 测量含义 | med(min/max, n) | warn(med >) | crit(med >) |
|---|---|---|---|---|
| `queue_rt_recv` | L5 对端灌 100B×20@50ms,MCU 非阻塞收的队列往返 | **1223µs**(1194/1223, 20) | 10ms | 100ms |
| `queue_rt_send` | L5 MCU 阻塞发 100B×20 | **988µs**(840/1216, 20) | 10ms | 100ms |
| `chunk_wait` | L3 16×4KB,芯片 chunk 内 SENBUF 等待(探针) | **219µs**(213/378, 64) | 10ms | 500ms(spike) |
| `queue_rt_recv` | 队列往返对端灌 100B×20@50ms,MCU 非阻塞收的队列往返 | **1223µs**(1194/1223, 20) | 10ms | 100ms |
| `queue_rt_send` | 队列往返 MCU 阻塞发 100B×20 | **988µs**(840/1216, 20) | 10ms | 100ms |
| `chunk_wait` | 发送分块握手 16×4KB,芯片 chunk 内 SENBUF 等待(探针) | **219µs**(213/378, 64) | 10ms | 500ms(spike) |
| `logtax_line` | 60 字符 printf @115200(本地) | **4402µs**(4396/8779, 10) | 10ms | 25ms |
| `req2first_mcu` | L6 10B 请求到齐 → 回显发出 | **986µs**(979/1015, 10) | 20ms | 200ms |
| `fullframe_mcu` | L7 9B 请求 → 3209B 全帧发给芯片(netTask 内 4 分块) | **6614µs**(6546/6649, 10) | 50ms | 500ms |
| `req2first_mcu` | 请求→首字节 10B 请求到齐 → 回显发出 | **986µs**(979/1015, 10) | 20ms | 200ms |
| `fullframe_mcu` | 整帧 3209B 往返 9B 请求 → 3209B 全帧发给芯片(netTask 内 4 分块) | **6614µs**(6546/6649, 10) | 50ms | 500ms |
(旧 run15 值对照:`queue_rt_recv` 5997 / `req2first_mcu` 319 / `fullframe_mcu` 6028µs——前两项差异均为
per-recv 日志移除的调度效应,非芯片行为变化:`queue_rt_recv` 差值 4.8ms ≈ 一行日志税;`req2first_mcu`
@@ -364,18 +364,18 @@ per-recv 日志移除的调度效应,非芯片行为变化:`queue_rt_recv`
| 段 | med(min/max) | 说明 |
|---|---|---|
| L6 端到端(op→收到回显) | 3.9ms(1.7/12.0) | 比 MCU 侧 `req2first_mcu` 多 ~2.9ms → PC/线路段 |
| L7 首字节 | 10.0ms(4.0/13.8) | 同上量级 |
| L7 全帧 3209B | 57.9ms(53.6/69.9) | 每帧固定 4 个 recv 批次;**尾批 137B 稳定迟到 ~50ms**(§7.3) |
| L7 CRC 校验 | 10/0 fail | 帧格式/CRC 正确(生产格式对齐后) |
| L3 flood 64KB(16×4KB) | 7.5~8.7ms/次 | ≈2ms/KB,满块无尾批惩罚(MCU 发送路径,与 per-recv 日志无关) |
| 请求→首字节端到端(op→收到回显) | 3.9ms(1.7/12.0) | 比 MCU 侧 `req2first_mcu` 多 ~2.9ms → PC/线路段 |
| 整帧 3209B 往返首字节 | 10.0ms(4.0/13.8) | 同上量级 |
| 整帧 3209B 往返全帧 3209B | 57.9ms(53.6/69.9) | 每帧固定 4 个 recv 批次;**尾批 137B 稳定迟到 ~50ms**(§7.3) |
| 整帧 3209B 往返 CRC 校验 | 10/0 fail | 帧格式/CRC 正确(生产格式对齐后) |
| 发送分块握手 flood 64KB(16×4KB) | 7.5~8.7ms/次 | ≈2ms/KB,满块无尾批惩罚(MCU 发送路径,与 per-recv 日志无关) |
**M2 三个结论**:
1. **安静档不存在重传台阶**——六段全部 `verdict=OK`、零尖峰、p95≈med;会话级路径的 MCU 净成本
~1.2ms(`queue_rt_recv`,P0-b 删日志后;旧值 5.997ms 含 per-recv 日志税 4.4ms/行);
2. **芯片 chunk 级不是瓶颈**——`chunk_wait` ≈219µs ≈ 每 chunk SPI 成本(1.61ms)的 1/7,
`SENBUF_FREE` 等待远小于 SPI 搬运本身;
3. **L7 全帧的最后 137B 尾巴稳定迟到 ~50ms**(10/10 帧)——尾批主导整帧时长,MCU 侧发完 3209B 仅 6.6ms
3. **整帧 3209B 往返全帧的最后 137B 尾巴稳定迟到 ~50ms**(10/10 帧)——尾批主导整帧时长,MCU 侧发完 3209B 仅 6.6ms
→ 延迟不在 MCU 发送路径,在芯片 TCP 尾段发送/对端 ACK 交互(M3 定性见 §7.3)。
### 6.3 M3 段(Phase 14,生产路径 + 模拟器 Δ)
@@ -384,9 +384,9 @@ per-recv 日志移除的调度效应,非芯片行为变化:`queue_rt_recv`
| 段(seg) | 测量含义 | 实测 med(min/max, n) | warn(med >) | crit(med >) |
|---|---|---|---|---|
| `ui_loop`(L11) | uiTask 生产循环实测周期(渲染+轮询+osDelay(10)) | **10986µs**(10951/11022, 120) | 25ms | 50ms |
| `prod_cycle_mcu`(L8) | LCDMEM 取 1bpp + 组帧 + net_send 4 分块(不含 10ms 拾取拍) | **6707µs**(6701/6757, 50) | 20ms | 200ms |
| `relisten`(L9) | 断线后 auto-relisten 全程(**修复后**:单次尝试 + netTask 延迟重试) | **89000µs ×3**(三次同值) | 100ms | 500ms |
| `ui_loop`(uiTask 节拍) | uiTask 生产循环实测周期(渲染+轮询+osDelay(10)) | **10986µs**(10951/11022, 120) | 25ms | 50ms |
| `prod_cycle_mcu`(生产路径帧周期) | LCDMEM 取 1bpp + 组帧 + net_send 4 分块(不含 10ms 拾取拍) | **6707µs**(6701/6757, 50) | 20ms | 200ms |
| `relisten`(连接/重监听) | 断线后 auto-relisten 全程(**修复后**:单次尝试 + netTask 延迟重试) | **89000µs ×3**(三次同值) | 100ms | 500ms |
(旧值对照:`prod_cycle_mcu` 7142µs → 6707µs = P0-b 移除请求路径 per-recv 日志的 ~435µs;
`ui_loop`/`relisten` 两档同值 = 与日志无关的段。)
@@ -405,7 +405,7 @@ D10 修复后实测 89ms(= 30ms 重试间隔 + 约 60ms 芯片沉降),故
| 帧 CRC / 位图非平凡 | 50/0、50/50 | 50/0、50/50 | — | 全通过 |
| 连接 / 断开重连(+INIT) | 20.9ms / 22.6ms(3/32.6) | 10.5ms / 32.0ms | — | ≤150ms |
(模拟器列为 2026-09-21 基线,模拟器工程不受 L3 影响(公开 API 不变);真机列 =
(模拟器列为 2026-09-21 基线,模拟器工程不受发送分块握手影响(公开 API 不变);真机列 =
**2026-09-22 16:42 挂起 recv 构建重锁**(`m3_data/m3_pending_quiet.json`,50/50 帧全 OK、零尖峰)。
上一档真机值(P0-a/P0-b 分片代码,同日 11:35):帧周期 61.6ms / 首字节 9.6ms / 尾批 52.6ms /
重连 17.1/22.4ms → pending 构建:帧周期 med **58.9ms**(−2.7ms)、尾批 **50.4ms**(−2.2ms)——
@@ -428,29 +428,29 @@ med 65.0ms/max 133.5ms(133.5 = 压力启动瞬态单帧);**挂起 recv 化
| 段 | 含义 | 实测(med) | min/max | n | 阈值 warn/crit | 判定 |
|---|---|---|---|---|---|---|
| **L1** `spi_cmd_*` | SPI 字节命令(3 条) | 10 / 15 / 15µs | 10~16µs | 100 | 40/100µs | OK |
| **L2** `spi_dma_*` | SPI DMA 读(4/64/1024/2048B) | 20 / 114 / **1611** / 3208µs | ±2µs | 100 | 见 §6.1 | OK |
| **L3** `chunk_wait` | 写发送缓冲 → `SENBUF_FREE`(每 chunk) | **219µs** | 213/378µs | 64 | 10ms/500ms | OK |
| **L5** `queue_rt_recv` | 调用方 `net_recv(DONTWAIT)` 队列全程 | **1223µs**¹ | 1194/1223µs | 20 | 10ms/100ms | OK |
| **L5** `queue_rt_send` | 调用方 `net_send` 队列全程 | **988µs**¹ | 840/1216µs | 20 | 10ms/100ms | OK |
| **L6** `req2first_mcu` | 10B 请求到齐 → 回显发出(MCU 侧) | **986µs** | 979/1015µs | 10 | 20ms/200ms | OK |
| **L7** `fullframe_mcu` | 9B 请求 → 3209B 全帧交给芯片 | **6614µs** | 6546/6649µs | 10 | 50ms/500ms | OK |
| **L8** `prod_cycle_mcu` | **生产路径**:LCDMEM 取帧+组帧+net_send(MCU 净成本) | **6706µs**(L3 重锁) | 6698/6720µs | 50 | 20ms/200ms | OK |
| **L10** `logtax_line` | 一行 60 字符 `printf` @115200 | **4402µs** | 4396/8779µs | 10 | 10ms/25ms | OK(D6 量化) |
| **L11** `ui_loop` | uiTask 生产循环实测周期 | **10986µs**(L3 重锁,值不变) | 10951/11022µs | 120 | 25ms/50ms | OK |
| **L9** `relisten` | 断线后自动重监听耗时(MCU 侧,修复后) | **90000µs**(L3 重锁) | 90000µs ×3 | 3 | 100ms/500ms | OK |
| **L4/L8** peer cycle | 生产客户端完整帧周期(发→收齐 3209B) | **58.9ms**(L3 重锁) | 47.7/72.1ms | 50 | — | 见 §7 |
| **Δ** | `peer cycle(设备) − peer cycle(模拟器)` | **52.1ms**(L3 重锁) | — | 50/50 | ≤80ms | 见 §7.1 |
| **SPI 字节命令** `spi_cmd_*` | SPI 字节命令(3 条) | 10 / 15 / 15µs | 10~16µs | 100 | 40/100µs | OK |
| **SPI DMA** `spi_dma_*` | SPI DMA 读(4/64/1024/2048B) | 20 / 114 / **1611** / 3208µs | ±2µs | 100 | 见 §6.1 | OK |
| **发送分块握手** `chunk_wait` | 写发送缓冲 → `SENBUF_FREE`(每 chunk) | **219µs** | 213/378µs | 64 | 10ms/500ms | OK |
| **队列往返** `queue_rt_recv` | 调用方 `net_recv(DONTWAIT)` 队列全程 | **1223µs**¹ | 1194/1223µs | 20 | 10ms/100ms | OK |
| **队列往返** `queue_rt_send` | 调用方 `net_send` 队列全程 | **988µs**¹ | 840/1216µs | 20 | 10ms/100ms | OK |
| **请求→首字节** `req2first_mcu` | 10B 请求到齐 → 回显发出(MCU 侧) | **986µs** | 979/1015µs | 10 | 20ms/200ms | OK |
| **整帧 3209B 往返** `fullframe_mcu` | 9B 请求 → 3209B 全帧交给芯片 | **6614µs** | 6546/6649µs | 10 | 50ms/500ms | OK |
| **生产路径帧周期** `prod_cycle_mcu` | **生产路径**:LCDMEM 取帧+组帧+net_send(MCU 净成本) | **6706µs**(发送分块握手重锁) | 6698/6720µs | 50 | 20ms/200ms | OK |
| **日志税** `logtax_line` | 一行 60 字符 `printf` @115200 | **4402µs** | 4396/8779µs | 10 | 10ms/25ms | OK(D6 量化) |
| **uiTask 节拍** `ui_loop` | uiTask 生产循环实测周期 | **10986µs**(发送分块握手重锁,值不变) | 10951/11022µs | 120 | 25ms/50ms | OK |
| **连接/重监听** `relisten` | 断线后自动重监听耗时(MCU 侧,修复后) | **90000µs**(发送分块握手重锁) | 90000µs ×3 | 3 | 100ms/500ms | OK |
| **芯片→线上+PC/生产路径帧周期** peer cycle | 生产客户端完整帧周期(发→收齐 3209B) | **58.9ms**(发送分块握手重锁) | 47.7/72.1ms | 50 | — | 见 §7 |
| **Δ** | `peer cycle(设备) − peer cycle(模拟器)` | **52.1ms**(发送分块握手重锁) | — | 50/50 | ≤80ms | 见 §7.1 |
¹ L5 两行为 2026-09-22 上午分片时代(M2 B2 档)数值:L3 对 DONTWAIT 快速路径**逐位不变**(dispatch
¹ 队列往返两行为 2026-09-22 上午分片时代(M2 B2 档)数值:发送分块握手对 DONTWAIT 快速路径**逐位不变**(dispatch
→ `net_poll` → `get_recv_len` 读,无 readable 门控),pending tick 无 pending 时为纯指针读零 SPI
(µs 级)——机制上不影响 L5;M2 重验未在本轮执行(低风险,留待 Phase 13 下次窗口,见 `docs/任务规划/README.md` 条目「L5 队列往返基线待重验」)。
(µs 级)——机制上不影响队列往返;M2 重验未在本轮执行(低风险,留待 Phase 13 下次窗口,见 `docs/任务规划/README.md` 条目「队列往返队列往返基线待重验」)。
数据来源:L1/L2 = §6.1;L3/L5/L6/L7 = M2 B2 档(200ms、per-recv 日志已删)串口 `[LAT]` 行
(`m2_data/m2_quiet_r200.json`);L8/L9/L11 = **挂起 recv 构建 2026-09-22 16:42/16:43 重锁**
(`m3_data/m3_pending_quiet.json` / `m3_pending_stress.json` 之 MCU 侧 `[LAT]` 行);L10 = M3 静默档
(`m3_data/m3_dev_quiet_r200.json`,与 L3 无关的串口税段);A/B 对照档 = `m2_quiet_r500.json`(旧条件);
peer 侧 = `m3_data/m3_pending_*.json`(L3 重锁)与 `m3_data/m3_dev_*_r200.json`(上一档,历史对照)。
数据来源:SPI 字节命令/SPI DMA = §6.1;发送分块握手/队列往返/请求→首字节/整帧 3209B 往返 = M2 B2 档(200ms、per-recv 日志已删)串口 `[LAT]` 行
(`m2_data/m2_quiet_r200.json`);生产路径帧周期/连接/重监听/uiTask 节拍 = **挂起 recv 构建 2026-09-22 16:42/16:43 重锁**
(`m3_data/m3_pending_quiet.json` / `m3_pending_stress.json` 之 MCU 侧 `[LAT]` 行);日志税 = M3 静默档
(`m3_data/m3_dev_quiet_r200.json`,与发送分块握手无关的串口税段);A/B 对照档 = `m2_quiet_r500.json`(旧条件);
peer 侧 = `m3_data/m3_pending_*.json`(发送分块握手重锁)与 `m3_data/m3_dev_*_r200.json`(上一档,历史对照)。
旧 run15 摘录(`m2_serial_excerpt_run15.txt`)保留作 A/B 历史对照。
### 6.5 重锁规则
@@ -496,8 +496,8 @@ peer 侧 = `m3_data/m3_pending_*.json`(L3 重锁)与 `m3_data/m3_dev_*_r200.
| 对照 | 观测 | 说明 |
|---|---|---|
| ① 同一客户端 → 模拟器 | tail = **0.0ms**,cycle 6.8ms | 客户端栈、Windows、请求格式全部相同 → 尾批**不是 PC 侧**造成 |
| ② M2 `L3` 批量排空(16×4096B,全满块) | 65536B / **125.4ms**(≈2ms/KB) | 16 个满块若各带 50ms 尾巴应 ≥800ms → **满块无此惩罚** |
| ③ 压力档(头阻塞)下的 L7/生产帧 | tail 仍 43~62ms | 尾批**不受 netTask 拥塞影响** → 属芯片 TX 行为 |
| ② M2 `发送分块握手` 批量排空(16×4096B,全满块) | 65536B / **125.4ms**(≈2ms/KB) | 16 个满块若各带 50ms 尾巴应 ≥800ms → **满块无此惩罚** |
| ③ 压力档(头阻塞)下的整帧 3209B 往返/生产帧 | tail 仍 43~62ms | 尾批**不受 netTask 拥塞影响** → 属芯片 TX 行为 |
### 7.3 尾批 ~52ms:机理与带内验证(全部不需要抓包)
@@ -513,7 +513,7 @@ peer 侧 = `m3_data/m3_pending_*.json`(L3 重锁)与 `m3_data/m3_dev_*_r200.
| 1 | **带内 ACK 探针** `--ack-probe` | 帧未收齐时立刻发 **1 个解析器会忽略的字节**(0x00,非 TAG 0xAA)→ 该 TCP 段**捎带**对已收数据的 ACK | **3.9ms** | **13.7ms** | 扣住**可被对端 ACK 解除** ⇒ 等 ACK(Nagle 式),非芯片固定定时器 |
| 2 | **对端立即 ACK** `--ack-fast` | Windows 每 socket 关延迟 ACK:`SIO_TCP_SET_ACK_FREQUENCY=1`(ctypes 调 `WSAIoctl`,**不发任何探测字节**) | **3.9ms** | **20.7ms** | **根因 = 对端延迟 ACK**(去掉它,尾批自行消失) |
| 3 | 对照:模拟器(同 peer/同 Windows) | — | 0.0ms | 6.8ms | 芯片侧才有该扣住行为 |
| 4 | 对照:满块批量(M2 L3,16×4096B) | — | 无累积惩罚(64KB/125ms) | — | **满 MSS 段不受影响** |
| 4 | 对照:满块批量(M2 发送分块握手,16×4096B) | — | 无累积惩罚(64KB/125ms) | — | **满 MSS 段不受影响** |
> 实验 1/2 已固化为 peer 选项:`--ack-probe`(可移植:任何 OS/客户端只要在帧未收齐时发一个字节即可)、
> `--ack-fast`(仅 Windows,无需改协议)。原始数据:`m3_data/m3_tail_ackprobe.json`、`m3_data/m3_tail_ackfast.json`。
@@ -549,11 +549,11 @@ peer 侧 = `m3_data/m3_pending_*.json`(L3 重锁)与 `m3_data/m3_dev_*_r200.
### 7.5 队列与调度:静默档不存在台阶
- **L5 队列往返**:收 1223µs / 发 988µs(P0-b 后;旧值 5997/987µs)——收段 ≈ 1 个 netTask 循环周期量级,
- **队列往返队列往返**:收 1223µs / 发 988µs(P0-b 后;旧值 5997/987µs)——收段 ≈ 1 个 netTask 循环周期量级,
属**调度周期**而非排队拥塞(旧收值 5997µs 含 per-recv 日志税 4.4ms/行,已移除);
`queue_rt_send` 988µs ≈ 1 个循环周期,发送路径没有额外排队;
- **L3 chunk 级**:219µs ≈ 每 chunk SPI 成本的 1/7 → **芯片 TX 缓冲不是瓶颈**,SPI 搬运才是;
- **L11 uiTask 周期**:10986µs(= osDelay(10) + 渲染开销 ≈ 1ms)→ **LVGL 渲染只占约 1ms/帧**,
- **发送分块握手 chunk 级**:219µs ≈ 每 chunk SPI 成本的 1/7 → **芯片 TX 缓冲不是瓶颈**,SPI 搬运才是;
- **uiTask 节拍 uiTask 周期**:10986µs(= osDelay(10) + 渲染开销 ≈ 1ms)→ **LVGL 渲染只占约 1ms/帧**,
uiTask 不是延迟来源;
- **结论:调优先级/栈不会改善体感**——预算应花在尾批解法(已做)与 D20(§8.2)。
@@ -650,17 +650,17 @@ sock4 auto-relisten ok / relisten retry ok ← 监听器随后自行恢复
| 现象(段超标) | 首要怀疑 | 动作 |
|---|---|---|
| L1/L2 超 crit | SPI 走线/PCB/SPI2 分频或 DMA 配置 | 对照 `docs/问题记录/CH395F_Trap_Records.md`;逻辑分析仪抓 SCK/CS |
| L3 spike ≥200ms(连续台阶;旧固件 500ms) | **D1 重传 / D7 链路** | Wireshark `tcp.analysis.retransmission`;查 PHY 协商/网线/Wi-Fi |
| L3 基线(非 spike)抬高 | 芯片发送路径 / 中断响应 | 对照 `docs/芯片手册/CH395F.md` §5.17/5.18(注意:状态寄存器**不被 SET 类命令更新**,`GET_CMD_STATUS` 恒读到 RESET 的 0x10 陈旧残留,2026-09-22 台架实证——不可用作 SET 值验收) |
| L5 压力档爆表 | **头阻塞(已修)→ 回归** | 定位触发源(阻塞 recv 的 FTP/对时);核对 挂起 recv 在位(`net_recv_locked` 阻塞分支 = 登记 pending 而非等待;`net_pending_tick` 在主循环) |
| L5/L6 静默档抬高 | D5 调度(任务抢占/节拍) | 查任务优先级与 osDelay;`stack_report.py` 核对栈水位 |
| L6/L7 整体抬高且 L1~L5 正常 | 线路+PC 段(L4) | Wireshark 段间间隙;`Δ` 对照定位 MCU 侧净贡献 |
| **L7/L8 尾批固定迟到 ~50ms** | 已定性(§7.3):次 MSS 尾段被 Nagle 扣住 + Windows 延迟 ACK | 客户端侧解法已实测(§7.4):`--ack-probe` / `--ack-fast` → tail 52→3.9ms;生产客户端已实装;**设备侧无解**,不必再投入 |
| **L5 出现重复投递**(收到 > 发送) | **D11**:芯片 TCP RX 饱和重复递交 | 降低灌入速率/加抽干(§8 D11) |
| SPI 字节命令/SPI DMA 超 crit | SPI 走线/PCB/SPI2 分频或 DMA 配置 | 对照 `docs/问题记录/CH395F_Trap_Records.md`;逻辑分析仪抓 SCK/CS |
| 发送分块握手 spike ≥200ms(连续台阶;旧固件 500ms) | **D1 重传 / D7 链路** | Wireshark `tcp.analysis.retransmission`;查 PHY 协商/网线/Wi-Fi |
| 发送分块握手基线(非 spike)抬高 | 芯片发送路径 / 中断响应 | 对照 `docs/芯片手册/CH395F.md` §5.17/5.18(注意:状态寄存器**不被 SET 类命令更新**,`GET_CMD_STATUS` 恒读到 RESET 的 0x10 陈旧残留,2026-09-22 台架实证——不可用作 SET 值验收) |
| 队列往返压力档爆表 | **头阻塞(已修)→ 回归** | 定位触发源(阻塞 recv 的 FTP/对时);核对挂起 recv 在位(`net_recv_locked` 阻塞分支 = 登记 pending 而非等待;`net_pending_tick` 在主循环) |
| 队列往返/请求→首字节静默档抬高 | D5 调度(任务抢占/节拍) | 查任务优先级与 osDelay;`stack_report.py` 核对栈水位 |
| 请求→首字节/整帧 3209B 往返整体抬高且 SPI 字节命令~队列往返正常 | 线路+PC 段(芯片→线上+PC) | Wireshark 段间间隙;`Δ` 对照定位 MCU 侧净贡献 |
| **整帧 3209B 往返/生产路径帧周期尾批固定迟到 ~50ms** | 已定性(§7.3):次 MSS 尾段被 Nagle 扣住 + Windows 延迟 ACK | 客户端侧解法已实测(§7.4):`--ack-probe` / `--ack-fast` → tail 52→3.9ms;生产客户端已实装;**设备侧无解**,不必再投入 |
| **队列往返出现重复投递**(收到 > 发送) | **D11**:芯片 TCP RX 饱和重复递交 | 降低灌入速率/加抽干(§8 D11) |
| **会话无征兆失步**(结果错位/挂死) | **D12**:net 层任务通知值语义 | 核对 `net_wait_result` 清除掩码全 1 + 按返回值判定(§8 D12/D12-a) |
| **L7 一直超时 / peer 报 `l7_crc.fail`** | **D15**:帧格式不一致 / peer 超读 | 对照 `ui_net_sink.c` 的 `remo_send`/`remo_handle`(§8 D15) |
| **会话跑完一段后无限循环、peer 空等 300s** | **D16**:Phase 13 状态机迁移目标错 | 核对顺序 L5→L3→L10→L6→L7→DONE(§8 D16) |
| **整帧 3209B 往返一直超时 / peer 报 `l7_crc.fail`** | **D15**:帧格式不一致 / peer 超读 | 对照 `ui_net_sink.c` 的 `remo_send`/`remo_handle`(§8 D15) |
| **会话跑完一段后无限循环、peer 空等 300s** | **D16**:Phase 13 状态机迁移目标错 | 核对顺序队列往返→发送分块握手→日志税→请求→首字节→整帧 3209B 往返→DONE(§8 D16) |
| **`[LAT]` 段行缺失/截断、wait-idle 判不出空闲** | **D12-b**:热路径日志洪水 | 删除 net 热路径逐调用日志(§8 D12-b) |
| **开机日志连续丢行**(含必检标记 `net init: OK` 等)但系统健康 | **日志并发竞态**:多任务并发 `printf` → 非重入 `fputc`/`HAL_UART_Transmit` 丢整行、UART 状态短暂卡住(2026-09-22 台架实证:boot 时 testTask 启动行与 netTask 日志并发 → 连续 4 行丢失、~2s 后自恢复) | 必现证据行采用"静默采集 + 延迟到安静窗口打印"模式(范例:P0-a 验收行曾按此模式实装,验收完成后探针已移除);根治 = 日志非阻塞/串行化(§10.2) |
| **`relisten` 段 FAIL、断线固定 ~588ms** | **D10** 回归(退回 `osDelay(500)`) | 核对 `net_listener_request`/`net_listener_tick` 非阻塞重试在位(§8 D10) |
@@ -670,8 +670,8 @@ sock4 auto-relisten ok / relisten retry ok ← 监听器随后自行恢复
| **`[LAT]` 多段数值雷同 / 全 0 却 verdict=OK** | **D18/D19**(测试工装) | 核对 `lat_probe_add_seg` 段名过滤 + `lat_time_init` 调用 + `med==0` 判无效 |
| **连接建立后 15~30s 无流量即被 RST** | **D13**:芯片空闲超时(芯片行为) | 客户端须容忍并重连(peer `--wait` 重试是正常路径) |
| **peer 重连后 MCU 状态滞留、新连接字节被吞** | **D14**:芯片静默回收 Socket | 对账⑤自动认领(手动 `net_resync_listen`/`net_relisten` 已退役,Phase 13 abort 期自愈亦交 net 层) |
| L8 超而 L7 正常 | 生产路径附加成本(uiTask 节拍/LVGL/日志) | 对照 L10/L11 与 §10.1(渲染已排除;per-recv 日志已删,非阻塞日志仍待办) |
| Δ 显著 >80ms | CH395F 栈净开销异常 | 回到 L1~L9 逐段排查 |
| 生产路径帧周期超而整帧 3209B 往返正常 | 生产路径附加成本(uiTask 节拍/LVGL/日志) | 对照日志税/uiTask 节拍与 §10.1(渲染已排除;per-recv 日志已删,非阻塞日志仍待办) |
| Δ 显著 >80ms | CH395F 栈净开销异常 | 回到 SPI 字节命令~连接/重监听逐段排查 |
---
@@ -747,7 +747,7 @@ sock4 auto-relisten ok / relisten retry ok ← 监听器随后自行恢复
| 数据文件 | 内容 |
|---|---|
| `m2_data/m2_quiet.json` | M2 安静档 peer 侧(run15 最终代码):L3~L7 端到端 + 逐帧尾批明细 |
| `m2_data/m2_quiet.json` | M2 安静档 peer 侧(run15 最终代码):发送分块握手~整帧 3209B 往返端到端 + 逐帧尾批明细 |
| `m2_data/m2_quiet_run13.json` | M2 run13(独立 PASS 对照) |
| `m2_data/m2_quiet_run14.json` | M2 run14(仅 peer 侧有效;MCU 侧因热路径日志洪水不作基线,D12-b) |
| `m2_data/m2_serial_excerpt_run13/15.txt` | M2 串口 `[LAT]`/`[NET]` 证据片段(旧基线,A/B 历史对照) |
@@ -759,9 +759,9 @@ sock4 auto-relisten ok / relisten retry ok ← 监听器随后自行恢复
| `m3_data/m3_dev_stress.json` | 头阻塞压力档(修复前:17.7s 尖峰) |
| `m3_data/m3_dev_stress_fixed.json` | 头阻塞压力档(修复后:med 74.7ms / max 106.8ms,零尖峰) |
| `m3_data/m3_dev_quiet_r200.json` | **2026-09-22 M3 静默档重锁基线**(50 帧,P0-a/P0-b 代码:帧周期 61.6ms) |
| `m3_data/m3_dev_stress_r200.json` | **2026-09-22 M3 压力档重锁基线**(45 帧,第 15 帧起 FTP stress:med 65.0ms / max 133.5ms,零尖峰)——分片时代数值,**已被 L3 档取代**(历史对照) |
| `m3_data/m3_pending_quiet.json` | **2026-09-22 M3 静默档 L3 重锁基线(当前)**(50 帧,pending 构建 16:42:帧周期 58.9ms / 首字节 9.2ms / 尾批 50.4ms,零尖峰,CRC 50/0) |
| `m3_data/m3_pending_stress.json` | **2026-09-22 M3 压力档 L3 重锁基线(当前)**(50 帧,第 25 帧起 FTP 空闲登录保持:stress 段 med 60.1ms / p95 66.4ms / max 69.3ms,零尖峰,CRC 50/0;MCU 侧 prod_cycle_mcu 6706µs 压力下平坦) |
| `m3_data/m3_dev_stress_r200.json` | **2026-09-22 M3 压力档重锁基线**(45 帧,第 15 帧起 FTP stress:med 65.0ms / max 133.5ms,零尖峰)——分片时代数值,**已被发送分块握手档取代**(历史对照) |
| `m3_data/m3_pending_quiet.json` | **2026-09-22 M3 静默档发送分块握手重锁基线(当前)**(50 帧,pending 构建 16:42:帧周期 58.9ms / 首字节 9.2ms / 尾批 50.4ms,零尖峰,CRC 50/0) |
| `m3_data/m3_pending_stress.json` | **2026-09-22 M3 压力档发送分块握手重锁基线(当前)**(50 帧,第 25 帧起 FTP 空闲登录保持:stress 段 med 60.1ms / p95 66.4ms / max 69.3ms,零尖峰,CRC 50/0;MCU 侧 prod_cycle_mcu 6706µs 压力下平坦) |
| `m3_data/m3_sim_quiet.json` | 模拟器基线 50 帧(Δ 对照,tail=0) |
| `m3_data/m3_tail_ackprobe.json` | 尾批实验 ①:帧未收齐即回 1 字节(捎带 ACK)→ tail 3.9ms |
| `m3_data/m3_tail_ackfast.json` | 尾批实验 ②:Windows 立即 ACK(不发探测字节)→ tail 3.9ms |