Keyboard 社区 · 求助:keyboard 在容器里启动偶发失败,日志如下

2.0k
KEr/keyboard·由 nikic 发布·6 小时前招聘已锁定

求助:keyboard 在容器里启动偶发失败,日志如下

这个问题我断断续续查了两周,中间走了不少弯路,把过程原样记下来,希望后面遇到的人能少花点时间。

排查顺序(按性价比排): 1. 先看下游 RT,八成问题不在自己身上 2. 再看连接池命中率与等待队列长度 3. 然后才是 GC 与内存分配 4. 最后才怀疑框架

关于取舍,我的判断是:如果团队里没有人长期盯这块,就不要引入第二套机制。两套并存的时候,出问题时你甚至要先花时间判断「这次是哪个在起作用」,那个成本比性能损失高得多。

监控这块我们也顺手改了:把原来的平均值告警换成分位数,并且按接口拆分。改完之后误报少了大概七成,值班同学的怨气肉眼可见地下降了。

顺便说一句,官方文档里这段其实有写,只是藏在一个很不起眼的位置。我是在翻源码注释的时候才发现的,注释里作者解释了为什么这么设计 —— 大意是「为了在极端情况下退化成可预期的行为」。

727 条评论

727 条评论

· 已加载前 120 条
我
Llinlin楼主·2 天前

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

516
Rran_bo·2 天前

已收藏。正好这周要改这块,少走很多弯路。

508
Ddev_zhou·昨天

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

499
Lli_ming·2 天前

已收藏。正好这周要改这块,少走很多弯路。

487
Oops_wang·2 天前

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

456
Wwinter·28 分钟前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

424
Bbob_chen·2 天前

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

399
Sslow_query·2 小时前

感谢分享真实数据,比很多只讲概念的文章有用得多。

389
Llinlin·2 天前

已收藏。正好这周要改这块,少走很多弯路。

383
Oops_wang·5 小时前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

7
Aalice_dev·2 天前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

371
Lli_ming·28 分钟前

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

355
Kkernel_panic版主·2 天前

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

342
Llinlin·2 天前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

58
Ttang_hao·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

1
Nnikic·2 天前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

1
Kkernel_panic·2 天前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

340
Lli_ming·昨天已编辑

已收藏。正好这周要改这块,少走很多弯路。

322
Wwinter·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

136
Rrase·2 天前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

493
Mmike_xu·2 天前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

488
Llinlin·1 小时前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

8
Aalice_dev·2 天前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

173
Rrase·2 天前已编辑

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

7
Kkite·28 分钟前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

170
Mmike_xu·2 天前

感谢分享真实数据,比很多只讲概念的文章有用得多。

45
Hhuang_ke·2 天前已编辑

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

106
Cchen_dev·2 天前

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

322
Ddev_zhou·2 天前

已收藏。正好这周要改这块,少走很多弯路。

339
Hhuang_ke·2 天前已编辑

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

316
Oops_wang楼主·5 小时前

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

239
Aalice_dev楼主·2 天前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

222
Rran_bo楼主·2 天前

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

205
Zzhu_zong·2 小时前已编辑

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

161
Ttang_hao楼主·2 天前

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

204
Ddev_zhou·2 天前

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

261
Sswoole_lee·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

249
Ddev_zhou·2 天前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

101
Wwinter·2 天前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

246
Rrase·2 天前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

208
Bbob_chen楼主·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

142
Zzhu_zong·3 分钟前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

1
Wwinter·1 小时前已编辑

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

195
Llinlin·2 天前

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

189
Mmike_xu·2 天前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

127
Hhuang_ke·2 小时前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

126
Zzhu_zong·2 天前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

110
Ddev_zhou·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

103
Ddev_zhou·2 天前

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

90
Zzhou_yi楼主·2 天前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

55
Mmike_xu楼主·2 天前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

23
Sslow_query·12 分钟前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

79
Kkernel_panic·2 天前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

475
Wwinter·2 天前

感谢分享真实数据,比很多只讲概念的文章有用得多。

1
Hhuang_ke·2 天前

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

442
Kkernel_panic楼主·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

493
Ttang_hao·昨天

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

79
Lli_ming·3 分钟前已编辑

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

73
Kkite·2 天前

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

42
Mmike_xu·2 天前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

459
Rran_bo·2 小时前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

359
Kkite·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

68
Rran_bo·28 分钟前

已收藏。正好这周要改这块,少走很多弯路。

69
Wwinter·2 天前

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

468
Cchen_dev·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

436
Cchen_dev楼主·2 天前已编辑

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

79
Rran_bo·2 天前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

21
Aalice_dev楼主·2 天前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

326
Zzhou_yi·2 天前

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

175
Zzhu_zong楼主·1 小时前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

4
Bbob_chen楼主·12 分钟前

已收藏。正好这周要改这块,少走很多弯路。

3
Hhuang_ke·28 分钟前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

57
Zzhou_yi·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

375
Cchen_dev·28 分钟前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

53
Hhuang_ke·2 天前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

47
Ddev_zhou·2 天前

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

47
Bbob_chen·3 分钟前已编辑

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

41
Sslow_query·2 天前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

446
Hhuang_ke·2 天前

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

17
Rran_bo·2 天前已编辑

感谢分享真实数据,比很多只讲概念的文章有用得多。

41
Zzhou_yi·1 小时前

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

40
Aalice_dev版主·2 天前

已收藏。正好这周要改这块,少走很多弯路。

32
Rran_bo·昨天已编辑

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

30
Llinlin·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

23
Rran_bo·2 天前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

120
Ddev_zhou·2 天前

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

9
Lli_ming·2 天前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

8
Kkite·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

27
Mmike_xu·2 天前

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

8
Rrase·2 小时前

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

5
Zzhou_yi·12 分钟前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

3
Nnikic·刚刚

这个结论和我们线上的观察一致。我们是在 QPS 到 3k 之后才撞上这个问题的,前压测阶段完全看不出来——因为压测流量太「干净」了,没有长尾请求。

178
Lli_ming·2 天前

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

419
Lli_ming楼主·2 天前

这里其实有个更简单的做法,不需要改架构:把这一层判断提前到网关,问题就消失了。代价是网关会多一次查表。

277
Sswoole_lee·28 分钟前

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

257
Cchen_dev楼主·2 小时前

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

1
Bbob_chen·2 天前

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

213
Kkernel_panic·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

15
Sslow_query·1 小时前

这不是 keyboard 的问题,是用法的问题。文档里写了这个 API 不是线程安全的,要自己在外面加锁。

13
Zzhou_yi·1 小时前已编辑

想请教一下,这个方案在容器里(内存限制 512Mi)会有什么变化?我们线上就是这么配的。

477
Rran_bo·2 天前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

17
Mmike_xu·3 分钟前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

7
Cchen_dev·2 天前已编辑

我们生产环境用了两年,没遇到过。不过我承认我们没上到这个量级,所以这个结论对我们没有参考价值。

101
Bbob_chen·2 天前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

163
Hhuang_ke·2 天前

补充一个反例:如果 keyboard 的版本低于 7.4,这段代码的语义是不一样的,别照抄。我们在灰度环境踩过,回滚了一次。

17
Sslow_query·1 小时前

感谢分享真实数据,比很多只讲概念的文章有用得多。

94
Aalice_dev·2 天前

感谢分享真实数据,比很多只讲概念的文章有用得多。

107
Mmike_xu·1 小时前已编辑

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

47
Hhuang_ke·昨天

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

41
Sswoole_lee·3 分钟前

有没有人做过对照实验?我做了一组,把变量控制到只剩这一个,结果差距只有 4%,基本在噪声范围内。所以我觉得主因可能不是这个。

1
Bbob_chen·2 天前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

3
Zzhu_zong·2 天前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

2
Mmike_xu·28 分钟前

楼主说的第 3 点我有不同看法。这里的取舍取决于你的读写比:读多写少的话,加缓存反而会放大不一致窗口。

68
Lli_ming·1 小时前

楼主这个排查思路值得学习。我们之前是直接从日志下手,绕了很大一圈。

1
Cchen_dev·2 天前

感谢分享真实数据,比很多只讲概念的文章有用得多。

1
Ddev_zhou·2 天前

贴一下我们的实测数据,8 核 16G,同样的场景:

| 并发 | P50 | P99 |
|---|---|---|
| 200 | 12ms | 88ms |
| 500 | 31ms | 340ms |

P99 在 500 并发时明显崩了,和你说的拐点基本吻合。

1
Cchen_dev·2 天前

感谢分享真实数据,比很多只讲概念的文章有用得多。

434
Kkernel_panic·2 天前

能不能给出最小复现?我本地跑了十分钟没复现出来,环境是 macOS + 最新版。

1
Bbob_chen·2 天前

同意上面说的。补充一点:如果开了这个选项,监控指标里的 GC 次数会翻倍,需要同步调整告警阈值,否则会一直误报。

1
Llinlin·2 天前

刚翻了下 keyboard 的源码,作者在注释里其实解释过为什么这么设计,大意是「为了在极端情况下退化成可预期行为」。

1

这是帖子详情页 /zh-CN/c/keyboard/post/p2。帖子与评论都由种子随机数确定性生成 —— 同一个帖子每次打开内容一致,因此可以直接分享链接、刷新、被搜索引擎收录。真实实现里这一页是 MySQL 读帖子 + Redis 缓存热帖 + 评论树按 path 字段一次性取出。

看数据表设计 →