| 2026-10-31 09:43:57.654 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.56 minutes] elapsed › |
| 2026-10-31 09:43:57.648 · INFO · cbg-trade · test |
| [report.go:221] ========== NewRoleCheck 卡顿分析报告 ========== 结果: success 总耗时: 33.062s 角色: 查询 209 / 待处理 208 / 已处理 208 / 成功 208 / 失败 0 / 已标记 0 并发数: 8 吞吐量: 6.29 roles/s 阶段耗时: - 分布式锁: 0.011s - 查询待处理角色: 0.059s - KVrocks状态过滤: 0.008s - 特殊服务器配置加载: 0.000s - worker处理: 32.842s - OpenIM通知: 0.142s 调用统计: - roledetail: 208次, 累计 185.708s, 平均 0.893s, P50 0.629s, P95 2.288s, P99 2.924s, 最大 3.252s - 角色估价: 49次, 累计 63.020s, 平均 1.286s, P50 0.115s, P95 4.264s, P99 8.695s, 最大 17.722s 请求明细: - roledetail HTTP请求: 208 - roledetail首次成功: 208 worker负载: - worker 1: 34件, 忙碌 30.311s - worker 2: 31件, 忙碌 30.196s - worker 3: 12件, 忙碌 30.373s - worker 4: 33件, 忙碌 32.356s - worker 5: 25件, 忙碌 32.841s - worker 6: 30件, 忙碌 31.016s - worker 7: 20件, 忙碌 31.143s - worker 8: 23件, 忙碌 30.494s 最慢角色 Top 10: - eid=202610310900113-584-XZQ2MUQ2FR52, worker=3, 总耗时=19.338s, roledetail=1.616s, 角色估价=17.722s - eid=202610251100113-669-5F3X9V9QVJ7H, worker=5, 总耗时=9.262s, roledetail=0.567s, 角色估价=8.695s - eid=202610072200113-527-HMXDYALQOJIK, worker=7, 总耗时=5.855s, roledetail=0.562s, 角色估价=5.292s - eid=202610101900113-527-NMW1NAEMNSRA, worker=8, 总耗时=4.659s, roledetail=0.395s, 角色估价=4.264s - eid=202610301600113-554-KDWAFU4YEKKH, worker=8, 总耗时=4.033s, roledetail=0.413s, 角色估价=3.620s - eid=202603252100113-407-A9UHUWX4G7DX, worker=3, 总耗时=3.980s, roledetail=0.450s, 角色估价=3.530s - eid=202610310800113-9-YU4BLCWZUCZC0D, worker=4, 总耗时=3.817s, roledetail=0.746s, 角色估价=3.072s - eid=202610302200113-583-GVYSNTK4ZG6Q, worker=6, 总耗时=3.252s, roledetail=3.252s - eid=202610101900113-39-99VGD4KPFF8AH, worker=2, 总耗时=3.239s, roledetail=3.239s - eid=202610310900113-132-OCILY0VTC4C8, worker=1, 总耗时=3.196s, roledetail=1.085s, 角色估价=2.111s =============================================== › |
| 2026-10-31 09:43:57.505 · INFO · cbg-trade · test |
| [single_line.go:49] new role check progress: 208/208 › |
| 2026-10-31 09:43:55.486 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.53 minutes] elapsed › |
| 2026-10-31 09:43:54.771 · INFO · cbg-trade · test |
| [single_line.go:49] new role check progress: 200/208 › |
| 2026-10-31 09:43:53.475 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610301400113-9-GVX5I9WNONWIKU server_id=9 reason=value status=1 status_code=OK kind_id=30 equip_type=3740 listing_status=2 › |
| 2026-10-31 09:43:53.094 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.48 minutes] elapsed › |
| 2026-10-31 09:43:52.417 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.47 minutes] elapsed › |
| 2026-10-31 09:43:50.551 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.44 minutes] elapsed › |
| 2026-10-31 09:43:50.061 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610310900113-149-XYGGHAPDF0HH server_id=149 reason=value status=1 status_code=OK kind_id=32 equip_type=3742 listing_status=2 › |
| 2026-10-31 09:43:48.097 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.40 minutes] elapsed › |
| 2026-10-31 09:43:44.552 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610252100113-459-A9KXRQG1DBK2 server_id=459 reason=value status=1 status_code=OK kind_id=34 equip_type=3744 listing_status=2 › |
| 2026-10-31 09:43:42.553 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610310900113-1024-DVDWWXZDRF6 server_id=1024 reason=value status=1 status_code=OK kind_id=51 equip_type=999 listing_status=2 › |
| 2026-10-31 09:43:41.772 · INFO · cbg-trade · test |
| [single_line.go:49] new role check progress: 100/208 › |
| 2026-10-31 09:43:41.027 · INFO · cbg-trade · test |
| [clothes.go:50] Initialized clothes cache with 5634 items › |
| 2026-10-31 09:43:40.390 · INFO · cbg-trade · test |
| [single_line.go:57] [sub_check] progress: processed=100/379 waiting=0 ordered=0 pushed=9 errors=0 › |
| 2026-10-31 09:43:40.324 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610310900113-911-XDOFVPAXILE7 server_id=911 reason=value status=1 status_code=OK kind_id=33 equip_type=3743 listing_status=2 › |
| 2026-10-31 09:43:39.271 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.25 minutes] elapsed › |
| 2026-10-31 09:43:39.270 · INFO · cbg-trade · test |
| [report.go:167] ========== RandomDraw 汇总与性能分析报告 ========== 结果: success_with_errors 总耗时: 14.679s 停止原因: checkpoint 请求服务: cbg-item-detail 页面: 尝试 8 / 成功 8 / 失败 0 数据: 处理 110 / 新增 96 / 重复 14 / 时间过滤 0 / 归档 105 Mailbox: 成功 96 / 失败 96 吞吐量: 7.49 items/s, 0.55 pages/s 异常: 请求 0 / 解析 0 / 状态异常 0 / 密保 0 / 无代理 0 / 致命 0 最后错误: mailbox Mailbox:RoleFlow is full (size: 100000) 阶段耗时: - 加载抓取配置: 0.109s - 分页抓取: 13.851s - 历史归档: 0.232s - 保存Checkpoint: 0.123s - 保存运行统计: 0.193s - 发送通知: 0.171s 调用统计: - Mailbox写入: 192次, 累计 3.585s, 平均 0.019s, P50 0.016s, P95 0.037s, P99 0.061s, 最大 0.255s - 批量写入: 7次, 累计 0.637s, 平均 0.091s, P50 0.091s, P95 0.095s, P99 0.095s, 最大 0.097s - 批量去重查询: 8次, 累计 0.233s, 平均 0.029s, P50 0.029s, P95 0.030s, P99 0.030s, 最大 0.031s - 角色列表服务请求: 8次, 累计 7.070s, 平均 0.884s, P50 0.656s, P95 1.355s, P99 1.355s, 最大 1.673s 最慢页面 Top 8: - page=2, 总耗时=2.740s, 获取=1.673s, 处理=1.067s - page=6, 总耗时=2.093s, 获取=1.292s, 处理=0.801s - page=1, 总耗时=1.917s, 获取=0.538s, 处理=1.379s - page=4, 总耗时=1.598s, 获取=0.697s, 处理=0.901s - page=8, 总耗时=1.524s, 获取=1.355s, 处理=0.169s - page=3, 总耗时=1.505s, 获取=0.449s, 处理=1.055s - page=7, 总耗时=1.285s, 获取=0.657s, 处理=0.629s - page=5, 总耗时=1.170s, 获取=0.410s, 处理=0.761s ================================================== › |
| 2026-10-31 09:43:38.434 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610310900113-326-55BDBP5NI1DI server_id=326 reason=value status=1 status_code=OK kind_id=50 equip_type=998 listing_status=2 › |