Polars·新人区 · polars-newbie 的日志规范:从「能看」到「能用」差了什么

805
POr/polars-newbie·由 dev_zhou 发布·1 小时前原理

polars-newbie 的日志规范:从「能看」到「能用」差了什么

先说背景。我们的场景是 polars-newbie + 三个下游服务,日均请求量七位数,峰值集中在晚上九点前后。

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

-- 出问题的那条查询:在 2000 万行上做了全表扫描
-- 加复合索引后 P99 从 1.8s 降到 42ms
SELECT id, title, created_at
  FROM posts
 WHERE community_id = ?
   AND status = 1
 ORDER BY score DESC
 LIMIT 20;

第一件事是把变量收敛干净。我们最开始同时在改配置和升级版本,结果两组数据的差异根本说不清是哪个带来的。后来回滚到只动一个变量,重跑了三遍,曲线才稳定下来。这一步很枯燥,但省不掉。

真正让我意外的是长尾。平均值一直很漂亮,P99 却在某个阈值之后直接跳了一个数量级。原因不在 polars-newbie 本身,而是我们上游的连接复用没做好,压测流量太「干净」,掩盖了长尾请求。

369 条评论

369 条评论

· 已加载前 120 条
我
Mmike_xu·2 天前

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

506
Mmike_xu·昨天已编辑

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

18
Aalice_dev·2 天前已编辑

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

154
Kkite·2 天前

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

506
Kkite楼主·2 天前

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

348
Llinlin·2 天前

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

505
Zzhu_zong·2 天前已编辑

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

453
Sswoole_lee·2 天前已编辑

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

430
Kkite·2 天前

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

423
Aalice_dev·2 天前

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

409
Ttang_hao楼主·2 天前

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

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

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

244
Zzhu_zong·2 天前

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

470
Zzhu_zong·2 天前

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

381
Kkernel_panic·2 天前

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

4
Mmike_xu·2 天前

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

1
Sswoole_lee·2 天前

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

358
Lli_ming·2 天前

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

349
Bbob_chen·5 小时前已编辑

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

336
Oops_wang·2 天前

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

316
Ddev_zhou·12 分钟前

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

305
Lli_ming·2 小时前

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

277
Nnikic·2 天前已编辑

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

1
Bbob_chen·2 天前

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

232
Ttang_hao·12 分钟前

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

228
Kkite·2 天前

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

181
Hhuang_ke·12 分钟前

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

262
Cchen_dev·2 天前

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

36
Kkernel_panic·2 天前

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

12
Rran_bo·12 分钟前已编辑

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

178
Kkernel_panic·昨天

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

2
Kkite楼主·2 天前

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

112
Cchen_dev·2 天前

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

170
Bbob_chen楼主·刚刚

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

99
Hhuang_ke·2 天前

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

401
Lli_ming·2 天前

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

205
Ddev_zhou·2 天前

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

201
Aalice_dev·2 天前

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

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

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

146
Ttang_hao·2 天前

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

277
Rrase·3 分钟前

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

141
Wwinter·2 天前

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

333
Cchen_dev楼主·2 小时前已编辑

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

10
Kkite·2 小时前已编辑

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

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

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

31
Wwinter楼主·刚刚

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

103
Cchen_dev·2 天前

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

51
Lli_ming·2 天前第 6 层

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

360
Mmike_xu·2 天前

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

1
Mmike_xu·2 天前

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

65
Lli_ming·2 天前

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

138
Ddev_zhou·2 天前

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

3
Lli_ming·2 小时前

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

118
Kkite·2 天前

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

85
Cchen_dev版主·2 天前

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

96
Rran_bo·12 分钟前

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

72
Llinlin·2 天前已编辑

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

444
Zzhu_zong·2 天前

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

1
Rrase·2 天前第 6 层

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

7
Zzhou_yi·2 天前

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

1
Kkite·2 天前

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

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

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

277
Lli_ming·2 天前

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

3
Hhuang_ke·刚刚已编辑

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

113
Rran_bo·2 天前

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

480
Zzhou_yi·2 小时前

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

1
Nnikic·2 天前

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

101
Kkernel_panic·2 天前

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

96
Sswoole_lee·刚刚

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

89
Ddev_zhou楼主·2 天前

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

387
Lli_ming·28 分钟前

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

45
Rrase·2 天前

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

82
Ttang_hao·2 天前

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

78
Zzhou_yi楼主·2 天前

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

14
Ttang_hao楼主·2 天前

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

2
Kkite·2 天前

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

59
Oops_wang版主·5 小时前

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

57
Kkernel_panic·2 天前

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

302
Hhuang_ke·2 天前

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

1
Bbob_chen楼主·2 天前

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

447
Aalice_dev·2 天前

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

203
Lli_ming楼主·2 天前已编辑

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

7
Kkernel_panic·2 天前

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

35
Mmike_xu·2 天前

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

5
Mmike_xu·刚刚

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

1
Hhuang_ke楼主·2 天前

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

16
Aalice_dev·2 天前

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

47
Llinlin·2 天前

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

94
Sslow_query·2 天前

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

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

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

3
Llinlin·2 天前已编辑

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

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

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

36
Kkernel_panic·2 天前已编辑

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

33
Nnikic·2 天前

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

31
Llinlin·刚刚

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

30
Bbob_chen·12 分钟前

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

361
Zzhu_zong·3 分钟前

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

1
Oops_wang·2 天前

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

68
Nnikic版主·2 天前

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

267
Kkite·刚刚

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

260
Rrase·2 天前

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

83
Nnikic·2 天前

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

327
Lli_ming·2 天前

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

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

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

398
Wwinter·2 天前第 6 层

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

74
Sswoole_lee楼主·昨天

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

29
Sswoole_lee·2 天前

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

1
Bbob_chen·2 天前

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

12
Rran_bo·2 天前

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

4
Lli_ming·2 天前

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

516
Mmike_xu·2 天前已编辑

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

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

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

26
Rrase·2 天前

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

1
Llinlin·2 天前

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

18
Kkite·2 天前

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

16
Hhuang_ke·2 天前

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

8
Sswoole_lee楼主·2 天前

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

494
Nnikic·1 小时前

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

8
Zzhou_yi·2 天前已编辑

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

7
Kkernel_panic·2 天前

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

331
Kkernel_panic·2 天前

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

271
Zzhou_yi·2 天前

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

7
Ttang_hao·刚刚

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

3
Rrase·2 天前已编辑

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

3
Aalice_dev版主·2 天前

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

3
Aalice_dev·2 天前

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

1
Mmike_xu·2 天前

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

1
Aalice_dev·2 天前

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

1

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

看数据表设计 →