| 2026-10-26 14:56:31.023 · INFO · cbg-trade · test |
| [update.go:140] [3/54] worker 4 check count 0, eid: 202610261400113-326-XHTLTRLEMJJG, status 2 › |
| 2026-10-26 14:56:30.844 · INFO · cbg-trade · test |
| [update.go:174] updating 54 random push reviews with 4 workers › |
| 2026-10-26 14:56:30.684 · INFO · cbg-trade · test |
| [update.go:86] random push review stats: total=40194, check_count<3=38807, distribution: 0=82, 1=154, 2=38571, 3=1387 › |
| 2026-10-26 14:56:30.465 · INFO · cbg-trade · test |
| [netstub.go:24] Starting net stub on port 9340... › |
| 2026-10-26 14:56:30.465 · INFO · cbg-trade · test |
| [update.go:189] update randomPushReview: concurrency: 4, limit: 800 › |
| 2026-10-26 14:56:30.465 · INFO · cbg-trade · test |
| [clickhouse.go:67] ClickHouse connected successfully, database=cbg_item host=192.168.101.203 port=9000 › |
| 2026-10-26 14:56:30.425 · INFO · cbg-trade · test |
| [database.go:732] mysql connection pool configured: MaxOpenConns=50, MaxIdleConns=10, ConnMaxLifetime=300s › |
| 2026-10-26 14:56:30.224 · INFO · cbg-trade · test |
| [database.go:694] mysql connecting to 124.220.31.60:3306/cbg as root › |
| 2026-10-26 14:56:30.196 · INFO · cbg-trade · test |
| [redis.go:41] Redis client initialized successfully › |
| 2026-10-26 14:56:30.157 · INFO · cbg-trade · test |
| [redis.go:34] Redis connecting to 192.168.101.203:6379, timeout: 30s › |
| 2026-10-26 14:56:30.156 · INFO · cbg-trade · test |
| [config.go:125] local ip [192.168.101.204 172.18.0.1 172.19.0.1 172.23.0.1 172.17.0.1 172.20.0.1 172.21.0.1] › |
| 2026-10-26 14:56:30.152 · INFO · cbg-trade · test |
| [main.go:38] Current directory: /app › |
| 2026-10-26 14:56:30.152 · INFO · cbg-trade · test |
| [main.go:31] Git Commit: revision -> [unknown] › |
| 2026-10-26 14:56:30.152 · INFO · cbg-trade · test |
| [main.go:30] Starting server, time -> [2026-10-26 14:56:30] › |
| 2026-10-26 14:56:21.937 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [1.87 minutes] elapsed › |
| 2026-10-26 14:56:21.787 · INFO · cbg-trade · test |
| [report.go:161] ========== SubCheck 卡顿分析报告 ========== 结果: success 总耗时: 112.068s 物品: 查询 2272 / 待处理 728 / 已处理 728 / 成功 728 / 失败 0 并发数: 7 历史归档: 823 吞吐量: 6.50 items/s 阶段耗时: - 查询待处理物品: 0.163s - KVrocks状态过滤: 0.071s - 历史数据归档: 0.335s - 订阅基础数据加载: 0.070s - 订阅规则加载: 0.690s - 历史推送价格加载: 0.121s - worker处理: 108.505s - 邮件发送: 2.111s 调用统计: - KVrocks状态写入: 728次, 累计 10.147s, 平均 0.014s, P50 0.011s, P95 0.030s, P99 0.051s, 最大 0.074s - itemdetail: 728次, 累计 14.446s, 平均 0.020s, P50 0.015s, P95 0.043s, P99 0.066s, 最大 0.234s - 估价: 728次, 累计 702.482s, 平均 0.965s, P50 0.588s, P95 2.852s, P99 4.851s, 最大 8.037s - 推送记录写入: 39次, 累计 4.024s, 平均 0.103s, P50 0.087s, P95 0.209s, P99 0.324s, 最大 0.333s - 物品描述解析: 291次, 累计 5.200s, 平均 0.018s, P50 0.013s, P95 0.036s, P99 0.077s, 最大 0.225s - 订阅匹配与推送: 728次, 累计 14.803s, 平均 0.020s, P50 0.012s, P95 0.090s, P99 0.182s, 最大 0.433s - 订阅规则匹配: 810次, 累计 5.326s, 平均 0.007s, P50 0.000s, P95 0.027s, P99 0.045s, 最大 0.226s worker负载: - worker 1: 107件, 忙碌 105.173s - worker 2: 96件, 忙碌 105.936s - worker 3: 119件, 忙碌 105.158s - worker 4: 105件, 忙碌 106.540s - worker 5: 112件, 忙碌 105.975s - worker 6: 107件, 忙碌 104.623s - worker 7: 82件, 忙碌 108.503s 最慢物品 Top 10: - eid=202610261400113-234-XSY702GNXGIJ, worker=1, 总耗时=8.073s, KVrocks状态写入=0.009s, itemdetail=0.015s, 估价=8.037s, 物品处理=8.073s, 订阅匹配与推送=0.012s - eid=202610261400113-197-D7KM0Q9GPTKH, worker=7, 总耗时=6.095s, KVrocks状态写入=0.011s, itemdetail=0.011s, 估价=6.061s, 物品处理=6.095s, 订阅匹配与推送=0.012s - eid=202610261400113-215-GHIADXZL7O6D, worker=4, 总耗时=5.734s, KVrocks状态写入=0.030s, itemdetail=0.049s, 估价=5.543s, 物品处理=5.734s, 物品描述解析=0.024s, 订阅匹配与推送=0.111s - eid=202610261400113-799-U0PM4T6ADSVA, worker=4, 总耗时=5.607s, KVrocks状态写入=0.022s, itemdetail=0.066s, 估价=5.497s, 物品处理=5.607s, 订阅匹配与推送=0.022s - eid=202610261400113-413-U4STYULLPMU2, worker=5, 总耗时=5.371s, KVrocks状态写入=0.017s, itemdetail=0.008s, 估价=5.336s, 物品处理=5.371s, 订阅匹配与推送=0.011s - eid=202610261400113-527-MGSJQIWWFLCG, worker=2, 总耗时=5.283s, KVrocks状态写入=0.010s, itemdetail=0.043s, 估价=5.221s, 物品处理=5.283s, 订阅匹配与推送=0.009s - eid=202610261400113-199-DCKNOLPTGIZ4, worker=2, 总耗时=5.280s, KVrocks状态写入=0.010s, itemdetail=0.026s, 估价=5.232s, 物品处理=5.280s, 物品描述解析=0.012s, 订阅匹配与推送=0.012s - eid=202610261400113-192-YGFUKBTAMSCU, worker=7, 总耗时=4.968s, KVrocks状态写入=0.010s, itemdetail=0.018s, 估价=4.933s, 物品处理=4.968s, 物品描述解析=0.006s, 订阅匹配与推送=0.007s - eid=202610261400113-9-MZ3ZPKPHF4MHWL, worker=5, 总耗时=4.890s, KVrocks状态写入=0.011s, itemdetail=0.010s, 估价=4.851s, 物品处理=4.890s, 订阅匹配与推送=0.018s - eid=202610261400113-285-ROPF6ZP2HS1E, worker=5, 总耗时=4.746s, KVrocks状态写入=0.022s, itemdetail=0.019s, 估价=4.694s, 物品处理=4.746s, 订阅匹配与推送=0.010s =========================================== › |
| 2026-10-26 14:56:21.787 · INFO · cbg-trade · test |
| [subcheck.go:376] [sub_check] completed: processed=728/728 waiting=0 ordered=0 pushed=39 errors=0 › |
| 2026-10-26 14:56:21.394 · INFO · cbg-trade · test |
| [main.go:63] Server exit, [1.86 minutes] elapsed › |
| 2026-10-26 14:56:21.389 · INFO · cbg-trade · test |
| [report.go:221] ========== NewRoleCheck 卡顿分析报告 ========== 结果: success 总耗时: 111.497s 角色: 查询 563 / 待处理 538 / 已处理 538 / 成功 538 / 失败 0 / 已标记 6 并发数: 11 吞吐量: 4.83 roles/s 阶段耗时: - 分布式锁: 0.008s - 查询待处理角色: 2.212s - KVrocks状态过滤: 0.023s - 特殊服务器配置加载: 0.000s - worker处理: 108.698s - OpenIM通知: 0.555s 调用统计: - KVrocks状态写入: 6次, 累计 0.075s, 平均 0.013s, P50 0.008s, P95 0.010s, P99 0.010s, 最大 0.036s - roledetail: 538次, 累计 726.905s, 平均 1.351s, P50 0.955s, P95 3.487s, P99 4.931s, 最大 7.569s - 基础抽签队列写入: 6次, 累计 0.262s, 平均 0.044s, P50 0.020s, P95 0.052s, P99 0.052s, 最大 0.131s - 审核记录写入: 6次, 累计 0.588s, 平均 0.098s, P50 0.098s, P95 0.098s, P99 0.098s, 最大 0.105s - 服务器查询: 6次, 累计 0.000s, 平均 0.000s, P50 0.000s, P95 0.000s, P99 0.000s, 最大 0.000s - 角色估价: 87次, 累计 416.077s, 平均 4.782s, P50 0.828s, P95 17.845s, P99 27.625s, 最大 42.790s 请求明细: - roledetail HTTP请求: 558 - roledetail重试: 20 - roledetail重试成功: 20 - roledetail首次成功: 518 worker负载: - worker 1: 51件, 忙碌 100.795s - worker 2: 35件, 忙碌 100.795s - worker 3: 40件, 忙碌 100.885s - worker 4: 71件, 忙碌 108.358s - worker 5: 36件, 忙碌 108.697s - worker 6: 56件, 忙碌 101.208s - worker 7: 52件, 忙碌 101.097s - worker 8: 35件, 忙碌 107.838s - worker 9: 73件, 忙碌 106.939s - worker 10: 61件, 忙碌 100.961s - worker 11: 28件, 忙碌 106.346s 最慢角色 Top 10: - eid=202610251600113-215-TSODQIKBF8CO, worker=11, 总耗时=44.043s, roledetail=1.253s, 角色估价=42.790s - eid=202610201600113-527-SS22JNVFRAHE, worker=6, 总耗时=29.373s, roledetail=2.460s, 角色估价=26.913s - eid=202610022000113-901-AGOODJPAZF0W, worker=2, 总耗时=29.055s, roledetail=1.431s, 角色估价=27.625s - eid=202610261400113-584-VTSSQKWA94CP, worker=7, 总耗时=22.880s, roledetail=0.521s, 角色估价=22.359s - eid=202610261100113-358-FOMR6NFPOKZT, worker=8, 总耗时=20.840s, KVrocks状态写入=0.006s, roledetail=5.724s, 基础抽签队列写入=0.015s, 审核记录写入=0.105s, 服务器查询=0.000s, 角色估价=14.989s - eid=202610261400113-527-MM9EBK56L9QU, worker=5, 总耗时=20.356s, KVrocks状态写入=0.008s, roledetail=0.915s, 基础抽签队列写入=0.020s, 审核记录写入=0.096s, 服务器查询=0.000s, 角色估价=19.318s - eid=202610261400113-352-ZQP2R9JTGDGU, worker=8, 总耗时=18.592s, roledetail=0.747s, 角色估价=17.845s - eid=202610261400113-352-QYQNQLTWRBHV, worker=3, 总耗时=18.018s, roledetail=0.892s, 角色估价=17.126s - eid=202610052300113-584-FLVH9YWQBHKO, worker=3, 总耗时=18.006s, roledetail=4.042s, 角色估价=13.964s - eid=202607122000113-554-CLHKILJXWF52, worker=8, 总耗时=15.896s, roledetail=1.365s, 角色估价=14.532s =============================================== › |
| 2026-10-26 14:56:20.833 · INFO · cbg-trade · test |
| [single_line.go:49] new role check progress: 538/538 › |