| 2026-11-03 18:35:18.643 · INFO · cbg-trade · test |
| [report.go:161] ========== SubCheck 卡顿分析报告 ========== 结果: success 总耗时: 114.531s 物品: 查询 2315 / 待处理 709 / 已处理 709 / 成功 709 / 失败 0 并发数: 7 历史归档: 950 吞吐量: 6.19 items/s 阶段耗时: - 查询待处理物品: 0.140s - KVrocks状态过滤: 0.081s - 历史数据归档: 0.487s - 订阅基础数据加载: 0.061s - 订阅规则加载: 0.575s - 历史推送价格加载: 0.123s - worker处理: 110.955s - 邮件发送: 2.106s 调用统计: - KVrocks状态写入: 709次, 累计 7.156s, 平均 0.010s, P50 0.009s, P95 0.019s, P99 0.032s, 最大 0.064s - itemdetail: 709次, 累计 13.812s, 平均 0.019s, P50 0.013s, P95 0.031s, P99 0.209s, 最大 0.854s - 估价: 709次, 累计 725.766s, 平均 1.024s, P50 0.557s, P95 3.547s, P99 6.611s, 最大 9.390s - 推送记录写入: 28次, 累计 2.125s, 平均 0.076s, P50 0.072s, P95 0.081s, P99 0.091s, 最大 0.169s - 物品描述解析: 242次, 累计 3.038s, 平均 0.013s, P50 0.011s, P95 0.025s, P99 0.040s, 最大 0.047s - 订阅匹配与推送: 709次, 累计 9.825s, 平均 0.014s, P50 0.010s, P95 0.036s, P99 0.093s, 最大 0.253s - 订阅规则匹配: 639次, 累计 3.159s, 平均 0.005s, P50 0.000s, P95 0.020s, P99 0.030s, 最大 0.048s worker负载: - worker 1: 89件, 忙碌 110.952s - worker 2: 110件, 忙碌 106.603s - worker 3: 105件, 忙碌 106.467s - worker 4: 97件, 忙碌 109.830s - worker 5: 104件, 忙碌 108.477s - worker 6: 114件, 忙碌 107.834s - worker 7: 90件, 忙碌 106.432s 最慢物品 Top 10: - eid=202611031800113-584-A8MJLLLPKJT5, worker=5, 总耗时=9.489s, KVrocks状态写入=0.004s, itemdetail=0.020s, 估价=9.381s, 物品处理=9.489s, 订阅匹配与推送=0.084s - eid=202611031800113-911-BZVEA12DSSLA, worker=7, 总耗时=9.427s, KVrocks状态写入=0.005s, itemdetail=0.021s, 估价=9.390s, 物品处理=9.427s, 订阅匹配与推送=0.010s - eid=202611031800113-459-W1DBYS8BWVJJ, worker=2, 总耗时=9.420s, KVrocks状态写入=0.014s, itemdetail=0.011s, 估价=9.385s, 物品处理=9.420s, 订阅匹配与推送=0.011s - eid=202611031800113-976-BUZ4QN7M4GRY, worker=1, 总耗时=8.783s, KVrocks状态写入=0.008s, itemdetail=0.021s, 估价=8.745s, 物品处理=8.783s, 物品描述解析=0.008s, 订阅匹配与推送=0.008s - eid=202611031800113-34-AGG36TMW9BF5U, worker=4, 总耗时=8.032s, KVrocks状态写入=0.015s, itemdetail=0.027s, 估价=7.971s, 物品处理=8.032s, 订阅匹配与推送=0.019s - eid=202611031800113-911-GBEMRF5UQCPV, worker=7, 总耗时=7.921s, KVrocks状态写入=0.014s, itemdetail=0.029s, 估价=7.841s, 物品处理=7.921s, 订阅匹配与推送=0.037s - eid=202611031800113-459-JIMLJ2DEUME3, worker=1, 总耗时=7.833s, KVrocks状态写入=0.008s, itemdetail=0.034s, 估价=7.709s, 物品处理=7.833s, 订阅匹配与推送=0.083s - eid=202611031800113-584-58BSF80NW8T4, worker=1, 总耗时=7.435s, KVrocks状态写入=0.010s, itemdetail=0.017s, 估价=7.395s, 物品处理=7.435s, 物品描述解析=0.012s, 订阅匹配与推送=0.012s - eid=202611031800113-527-R94OZIUFEPHR, worker=1, 总耗时=6.730s, KVrocks状态写入=0.024s, itemdetail=0.050s, 估价=6.611s, 物品处理=6.730s, 物品描述解析=0.045s, 订阅匹配与推送=0.045s - eid=202611031700113-689-78GSXM4GZ5L3, worker=3, 总耗时=6.044s, KVrocks状态写入=0.006s, itemdetail=0.012s, 估价=6.013s, 物品处理=6.044s, 订阅匹配与推送=0.013s =========================================== › |
| 2026-11-03 18:35:17.543 · INFO · cbg-trade · test |
| [token.go:260] get token from lib, new token: &{false 3163 10000 AXOD-YMEN-1853-5726 0 1793702117 0 lib token 2 0 0 200 0 0 0 0 0 {0001-01-01 00:00:00 +0000 UTC false}} › |
| 2026-11-03 18:35:16.535 · INFO · cbg-trade · test |
| [single_line.go:57] [sub_check] progress: processed=709/709 waiting=0 ordered=0 pushed=28 errors=0 › |
| 2026-11-03 18:35:12.596 · INFO · cbg-trade · test |
| [producer.go:173] [value-stats] producer buffer=0 sent=1000 dropped=0 › |
| 2026-11-03 18:35:12.115 · INFO · cbg-trade · test |
| [update.go:191] update success › |
| 2026-11-03 18:35:12.115 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [0.81 minutes] elapsed › |
| 2026-11-03 18:35:12.114 · INFO · cbg-trade · test |
| [update.go:176] updated 60/60 random push reviews › |
| 2026-11-03 18:35:12.030 · INFO · cbg-trade · test |
| [update.go:142] [57/60] worker 2 check count 2, eid: 202610271700113-149-ZEIQWDSQXPPT, status 2->6 › |
| 2026-11-03 18:35:11.782 · INFO · cbg-trade · test |
| [single_line.go:57] [sub_check] progress: processed=700/709 waiting=0 ordered=0 pushed=28 errors=0 › |
| 2026-11-03 18:35:11.726 · INFO · cbg-trade · test |
| [random_push_review.go:194] role get random push review, token: DTHF-NURN-3826-3772 › |
| 2026-11-03 18:35:11.645 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202611031700113-9-6J45IZ5XDY91JR server_id=9 reason=value status=1 status_code=OK kind_id=51 equip_type=999 listing_status=2 › |
| 2026-11-03 18:35:10.952 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [1.78 minutes] elapsed › |
| 2026-11-03 18:35:10.941 · INFO · cbg-trade · test |
| [report.go:221] ========== NewRoleCheck 卡顿分析报告 ========== 结果: success_with_errors 总耗时: 106.710s 角色: 查询 266 / 待处理 265 / 已处理 265 / 成功 259 / 失败 6 / 已标记 0 并发数: 8 吞吐量: 2.48 roles/s 阶段耗时: - 分布式锁: 0.013s - 查询待处理角色: 0.068s - KVrocks状态过滤: 0.007s - 特殊服务器配置加载: 0.000s - worker处理: 106.460s - OpenIM通知: 0.162s 调用统计: - roledetail: 265次, 累计 669.299s, 平均 2.526s, P50 1.432s, P95 8.498s, P99 14.066s, 最大 21.276s - 角色估价: 52次, 累计 132.477s, 平均 2.548s, P50 0.204s, P95 8.533s, P99 14.767s, 最大 15.224s 请求明细: - roledetail HTTP请求: 291 - roledetail重试: 32 - roledetail重试成功: 29 - roledetail首次成功: 230 worker负载: - worker 1: 42件, 忙碌 100.133s - worker 2: 21件, 忙碌 101.236s - worker 3: 33件, 忙碌 106.458s - worker 4: 33件, 忙碌 98.051s - worker 5: 36件, 忙碌 97.559s - worker 6: 29件, 忙碌 97.115s - worker 7: 34件, 忙碌 100.403s - worker 8: 37件, 忙碌 100.824s 最慢角色 Top 10: - eid=202604202100113-758-IIXMM6GVW1U6, worker=1, 总耗时=21.276s, roledetail=21.276s - eid=202610252200113-901-72DAJ8PJWA6U, worker=4, 总耗时=20.860s, roledetail=5.636s, 角色估价=15.224s - eid=202606050100113-407-IR6DDFJ1A7ZI, worker=8, 总耗时=19.473s, roledetail=19.455s, 角色估价=0.018s - eid=202607141400113-9-BKS3A2WWAU4FV5, worker=3, 总耗时=16.178s, roledetail=1.411s, 角色估价=14.767s - eid=202611020800113-215-GEIYZ4WRT5AV, worker=8, 总耗时=14.794s, roledetail=2.155s, 角色估价=12.639s - eid=202611021700113-807-6C9HNEABS8JP, worker=2, 总耗时=14.453s, roledetail=14.453s - eid=202611011800113-554-13NIPTCNGR0P, worker=6, 总耗时=14.334s, roledetail=5.963s, 角色估价=8.371s - eid=202610091900113-750-VD3VXYH6NZCC, worker=5, 总耗时=14.066s, roledetail=14.066s - eid=202610080100113-625-ASEWYM7HS6ZI, worker=6, 总耗时=13.231s, roledetail=13.231s - eid=202604041500113-935-9KPVTYHZDZYC, worker=7, 总耗时=13.020s, roledetail=13.020s 错误汇总: - roledetail: 6 - 角色处理: 6 =============================================== › |
| 2026-11-03 18:35:10.779 · INFO · cbg-trade · test |
| [single_line.go:49] new role check progress: 265/265 › |
| 2026-11-03 18:35:07.787 · INFO · cbg-trade · test |
| [update.go:140] [60/60] worker 4 check count 2, eid: 202610271700113-352-QIXNOCIFUGAI, status 2 › |
| 2026-11-03 18:35:07.431 · INFO · cbg-trade · test |
| [update.go:140] [58/60] worker 1 check count 2, eid: 202610271700113-72-HGAIOJXVN4DGV, status 2 › |
| 2026-11-03 18:35:07.424 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202611031800113-785-X4Q2ULVU41IX server_id=785 reason=value status=1 status_code=OK kind_id=51 equip_type=999 listing_status=2 › |
| 2026-11-03 18:35:06.048 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202611031800113-299-D5JCDXHEKNQO server_id=299 reason=value status=1 status_code=OK kind_id=34 equip_type=3744 listing_status=2 › |
| 2026-11-03 18:35:05.538 · INFO · cbg-trade · test |
| [update.go:142] [59/60] worker 3 check count 2, eid: 202610271700113-901-6J3IZMRCSQVY, status 2->6 › |
| 2026-11-03 18:35:05.335 · INFO · cbg-trade · test |
| [update.go:140] [52/60] worker 4 check count 1, eid: 202611031700113-9-TB59CKFR4TJ6KX, status 2 › |