| 2026-10-27 14:51:28.425 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [1.97 minutes] elapsed › |
| 2026-10-27 14:51:28.292 · INFO · cbg-trade · test |
| [report.go:161] ========== SubCheck 卡顿分析报告 ========== 结果: success 总耗时: 117.565s 物品: 查询 2335 / 待处理 721 / 已处理 721 / 成功 721 / 失败 0 并发数: 7 历史归档: 800 吞吐量: 6.13 items/s 阶段耗时: - 查询待处理物品: 0.178s - KVrocks状态过滤: 0.097s - 历史数据归档: 0.309s - 订阅基础数据加载: 0.069s - 订阅规则加载: 0.791s - 历史推送价格加载: 0.370s - worker处理: 113.707s - 邮件发送: 2.045s 调用统计: - KVrocks状态写入: 721次, 累计 10.762s, 平均 0.015s, P50 0.011s, P95 0.035s, P99 0.077s, 最大 0.264s - itemdetail: 721次, 累计 27.615s, 平均 0.038s, P50 0.015s, P95 0.046s, P99 0.253s, 最大 3.275s - 估价: 721次, 累计 723.249s, 平均 1.003s, P50 0.559s, P95 3.443s, P99 6.263s, 最大 9.763s - 推送记录写入: 32次, 累计 3.107s, 平均 0.097s, P50 0.096s, P95 0.103s, P99 0.105s, 最大 0.112s - 物品描述解析: 332次, 累计 5.991s, 平均 0.018s, P50 0.014s, P95 0.039s, P99 0.091s, 最大 0.230s - 订阅匹配与推送: 721次, 累计 14.388s, 平均 0.020s, P50 0.012s, P95 0.097s, P99 0.129s, 最大 0.231s - 订阅规则匹配: 862次, 累计 6.122s, 平均 0.007s, P50 0.000s, P95 0.027s, P99 0.067s, 最大 0.231s worker负载: - worker 1: 111件, 忙碌 110.530s - worker 2: 102件, 忙碌 110.406s - worker 3: 98件, 忙碌 110.361s - worker 4: 87件, 忙碌 113.704s - worker 5: 108件, 忙碌 110.385s - worker 6: 100件, 忙碌 110.358s - worker 7: 115件, 忙碌 110.299s 最慢物品 Top 10: - eid=202610271400113-527-IX5HHFERUBFU, worker=4, 总耗时=9.793s, KVrocks状态写入=0.006s, itemdetail=0.015s, 估价=9.763s, 物品处理=9.793s, 订阅匹配与推送=0.009s - eid=202610271400113-304-UEMOGFSAREWL, worker=6, 总耗时=9.705s, KVrocks状态写入=0.010s, itemdetail=0.014s, 估价=9.664s, 物品处理=9.705s, 订阅匹配与推送=0.017s - eid=202610250300113-412-BDSCUN2VS5MI, worker=4, 总耗时=9.327s, KVrocks状态写入=0.008s, itemdetail=2.833s, 估价=6.474s, 物品处理=9.327s, 物品描述解析=0.011s, 订阅匹配与推送=0.012s - eid=202610271400113-868-QF5D10XAY03A, worker=7, 总耗时=9.123s, KVrocks状态写入=0.023s, itemdetail=0.022s, 估价=9.053s, 物品处理=9.123s, 订阅匹配与推送=0.025s - eid=202610271400113-527-DK5XOOPUB2BQ, worker=3, 总耗时=7.377s, KVrocks状态写入=0.009s, itemdetail=0.012s, 估价=7.243s, 物品处理=7.377s, 订阅匹配与推送=0.114s - eid=202610271400113-855-I6RUEHI7QQ1X, worker=6, 总耗时=7.326s, KVrocks状态写入=0.013s, itemdetail=0.048s, 估价=7.240s, 物品处理=7.326s, 订阅匹配与推送=0.025s - eid=202610271400113-892-JLWW9WMOYPYV, worker=3, 总耗时=7.128s, KVrocks状态写入=0.020s, itemdetail=0.046s, 估价=7.046s, 物品处理=7.128s, 订阅匹配与推送=0.015s - eid=202610271400113-132-ICI9688CTGIW, worker=1, 总耗时=6.653s, KVrocks状态写入=0.027s, itemdetail=0.023s, 估价=6.573s, 物品处理=6.653s, 物品描述解析=0.029s, 订阅匹配与推送=0.030s - eid=202610271400113-669-QMTX01MTPMSL, worker=3, 总耗时=6.299s, KVrocks状态写入=0.007s, itemdetail=0.021s, 估价=6.263s, 物品处理=6.299s, 订阅匹配与推送=0.008s - eid=202610271400113-625-RBBZIBMBVTTT, worker=4, 总耗时=6.123s, KVrocks状态写入=0.015s, itemdetail=0.095s, 估价=6.003s, 物品处理=6.123s, 订阅匹配与推送=0.011s =========================================== › |
| 2026-10-27 14:51:28.292 · INFO · cbg-trade · test |
| [subcheck.go:376] [sub_check] completed: processed=721/721 waiting=0 ordered=0 pushed=32 errors=0 › |
| 2026-10-27 14:51:27.949 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [1.96 minutes] elapsed › |
| 2026-10-27 14:51:27.944 · INFO · cbg-trade · test |
| [report.go:221] ========== NewRoleCheck 卡顿分析报告 ========== 结果: success 总耗时: 117.218s 角色: 查询 599 / 待处理 586 / 已处理 586 / 成功 586 / 失败 0 / 已标记 6 并发数: 12 吞吐量: 5.00 roles/s 阶段耗时: - 分布式锁: 0.006s - 查询待处理角色: 0.093s - KVrocks状态过滤: 0.017s - 特殊服务器配置加载: 0.000s - worker处理: 116.949s - OpenIM通知: 0.153s 调用统计: - KVrocks状态写入: 6次, 累计 0.124s, 平均 0.021s, P50 0.011s, P95 0.018s, P99 0.018s, 最大 0.065s - roledetail: 586次, 累计 859.936s, 平均 1.467s, P50 1.089s, P95 3.836s, P99 6.353s, 最大 11.684s - 基础抽签队列写入: 4次, 累计 0.087s, 平均 0.022s, P50 0.017s, P95 0.022s, P99 0.022s, 最大 0.034s - 审核记录写入: 6次, 累计 0.537s, 平均 0.089s, P50 0.090s, P95 0.091s, P99 0.091s, 最大 0.092s - 服务器查询: 6次, 累计 0.000s, 平均 0.000s, P50 0.000s, P95 0.000s, P99 0.000s, 最大 0.000s - 角色估价: 110次, 累计 464.510s, 平均 4.223s, P50 0.397s, P95 19.217s, P99 30.787s, 最大 43.080s 请求明细: - roledetail HTTP请求: 591 - roledetail重试: 5 - roledetail重试成功: 5 - roledetail首次成功: 581 worker负载: - worker 1: 53件, 忙碌 114.481s - worker 2: 63件, 忙碌 104.333s - worker 3: 34件, 忙碌 109.376s - worker 4: 60件, 忙碌 116.947s - worker 5: 39件, 忙碌 107.716s - worker 6: 74件, 忙碌 114.530s - worker 7: 21件, 忙碌 108.822s - worker 8: 41件, 忙碌 104.772s - worker 9: 41件, 忙碌 108.107s - worker 10: 55件, 忙碌 104.112s - worker 11: 71件, 忙碌 115.826s - worker 12: 34件, 忙碌 116.177s 最慢角色 Top 10: - eid=202606231400113-1006-AW9ZWW7NBO5, worker=3, 总耗时=44.591s, KVrocks状态写入=0.010s, roledetail=1.395s, 基础抽签队列写入=0.017s, 审核记录写入=0.087s, 服务器查询=0.000s, 角色估价=43.080s - eid=202609201200113-926-7MCEU3BNUHIE, worker=7, 总耗时=43.260s, roledetail=1.411s, 角色估价=41.849s - eid=202610251200113-554-TJR7FTZ4GH6J, worker=4, 总耗时=32.859s, KVrocks状态写入=0.065s, roledetail=1.922s, 审核记录写入=0.086s, 服务器查询=0.000s, 角色估价=30.787s - eid=202610202000113-926-CWKTULWOQ6ZN, worker=12, 总耗时=27.616s, roledetail=0.751s, 角色估价=26.864s - eid=202609291100113-352-ZIBZ9QZLBEVI, worker=9, 总耗时=24.366s, roledetail=1.163s, 角色估价=23.203s - eid=202610271400113-554-SZUCYNCVJSEZ, worker=12, 总耗时=22.920s, roledetail=1.111s, 角色估价=21.808s - eid=202610211800113-352-8OJVOJ4OAFRG, worker=3, 总耗时=21.141s, roledetail=2.654s, 角色估价=18.486s - eid=202610271400113-554-YXXL3F2OE3SI, worker=2, 总耗时=19.920s, roledetail=0.704s, 角色估价=19.217s - eid=202610252100113-9-4M4LSXLA4PXM62, worker=8, 总耗时=19.885s, roledetail=0.936s, 角色估价=18.949s - eid=202610251200113-407-KVOSZKEP0WHE, worker=1, 总耗时=17.597s, roledetail=1.079s, 角色估价=16.517s =============================================== › |
| 2026-10-27 14:51:27.791 · INFO · cbg-trade · test |
| [single_line.go:49] new role check progress: 586/586 › |
| 2026-10-27 14:51:26.245 · INFO · cbg-trade · test |
| [single_line.go:57] [sub_check] progress: processed=721/721 waiting=0 ordered=0 pushed=32 errors=0 › |
| 2026-10-27 14:51:24.325 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610222100113-1003-ZHL65BLCHZG server_id=1003 reason=value status=1 status_code=OK kind_id=51 equip_type=999 listing_status=2 › |
| 2026-10-27 14:51:22.322 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610271400113-931-7GLRJPZAW1TK server_id=931 reason=value status=1 status_code=OK kind_id=31 equip_type=3741 listing_status=2 › |
| 2026-10-27 14:51:21.620 · INFO · cbg-trade · test |
| [user_perform_effect_price.go:38] Initialized user perform effect price cache with 87 items › |
| 2026-10-27 14:51:21.593 · INFO · cbg-trade · test |
| [user_perform_effect_price.go:38] Initialized user perform effect price cache with 87 items › |
| 2026-10-27 14:51:21.568 · INFO · cbg-trade · test |
| [user_perform_effect_price.go:38] Initialized user perform effect price cache with 87 items › |
| 2026-10-27 14:51:21.542 · INFO · cbg-trade · test |
| [user_perform_effect_price.go:38] Initialized user perform effect price cache with 87 items › |
| 2026-10-27 14:51:21.169 · INFO · cbg-trade · test |
| [single_line.go:57] [sub_check] progress: processed=700/721 waiting=0 ordered=0 pushed=29 errors=0 › |
| 2026-10-27 14:51:15.611 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610250700113-584-T5PAC1Q07WTG server_id=584 reason=value status=1 status_code=OK kind_id=34 equip_type=3744 listing_status=2 › |
| 2026-10-27 14:51:12.974 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610271400113-9-9TP57KCMEDWJLN server_id=9 reason=value status=1 status_code=OK kind_id=31 equip_type=3741 listing_status=2 › |
| 2026-10-27 14:51:11.545 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610271400113-857-8KKUEJL8SQSO server_id=857 reason=value status=1 status_code=OK kind_id=32 equip_type=3742 listing_status=2 › |
| 2026-10-27 14:51:08.187 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610271400113-857-QXS1BFNNRIHA server_id=857 reason=value status=1 status_code=OK kind_id=32 equip_type=3742 listing_status=2 › |
| 2026-10-27 14:51:05.366 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610271400113-976-ER3PGUKG0PI9 server_id=976 reason=value status=1 status_code=OK kind_id=33 equip_type=3743 listing_status=2 › |
| 2026-10-27 14:51:04.076 · INFO · cbg-trade · test |
| [eidtype.go:103] [eid_type] upstream lookup succeeded, eid=202610271400113-976-IK8WJGFZEZZL server_id=976 reason=value status=1 status_code=OK kind_id=33 equip_type=3743 listing_status=2 › |