Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[improvement](spill) improve spill log printing #34729

Merged
merged 2 commits into from May 12, 2024

Conversation

jacktengg
Copy link
Contributor

Proposed changes

Issue Number: close #xxx

Further comments

If this is a relatively large or complex change, kick off the discussion at dev@doris.apache.org by explaining why you chose the solution you did and what alternatives you considered, etc...

@doris-robot
Copy link

Thank you for your contribution to Apache Doris.
Don't know what should be done next? See How to process your PR

Since 2024-03-18, the Document has been moved to doris-website.
See Doris Document.

@jacktengg
Copy link
Contributor Author

run buildall

Copy link
Contributor

clang-tidy review says "All clean, LGTM! 👍"

1 similar comment
Copy link
Contributor

clang-tidy review says "All clean, LGTM! 👍"

@doris-robot
Copy link

TPC-H: Total hot run time: 40848 ms
machine: 'aliyun_ecs.c7a.8xlarge_32C64G'
scripts: https://github.com/apache/doris/tree/master/tools/tpch-tools
Tpch sf100 test result on commit ad2ec97ea3f33e8946b46e9a13cf3f4b10451852, data reload: false

------ Round 1 ----------------------------------
q1	17743	4725	4279	4279
q2	2011	193	191	191
q3	10947	1260	1222	1222
q4	10543	909	814	814
q5	7653	2676	2687	2676
q6	217	131	132	131
q7	994	591	576	576
q8	9375	2126	2146	2126
q9	9015	6670	6640	6640
q10	9184	3692	3718	3692
q11	453	247	239	239
q12	424	212	209	209
q13	18884	2952	2941	2941
q14	267	225	215	215
q15	514	477	474	474
q16	509	390	376	376
q17	971	651	751	651
q18	7999	7477	7473	7473
q19	3526	1519	1473	1473
q20	645	313	296	296
q21	5078	3869	3972	3869
q22	351	285	294	285
Total cold run time: 117303 ms
Total hot run time: 40848 ms

----- Round 2, with runtime_filter_mode=off -----
q1	4317	4247	4205	4205
q2	360	265	272	265
q3	2951	2739	2738	2738
q4	1874	1567	1641	1567
q5	5312	5284	5268	5268
q6	215	125	123	123
q7	2244	1857	1862	1857
q8	3209	3359	3328	3328
q9	8382	8306	8336	8306
q10	3910	3647	3734	3647
q11	563	476	485	476
q12	750	593	615	593
q13	16398	2935	2925	2925
q14	299	271	266	266
q15	511	488	470	470
q16	453	408	413	408
q17	1768	1505	1460	1460
q18	7744	7376	7454	7376
q19	1639	1574	1542	1542
q20	1921	1743	1756	1743
q21	4970	4880	4894	4880
q22	582	495	512	495
Total cold run time: 70372 ms
Total hot run time: 53938 ms

@doris-robot
Copy link

TPC-DS: Total hot run time: 186643 ms
machine: 'aliyun_ecs.c7a.8xlarge_32C64G'
scripts: https://github.com/apache/doris/tree/master/tools/tpcds-tools
TPC-DS sf100 test result on commit ad2ec97ea3f33e8946b46e9a13cf3f4b10451852, data reload: false

query1	908	361	339	339
query2	7316	2379	2411	2379
query3	6644	213	213	213
query4	22764	21167	21287	21167
query5	4170	430	422	422
query6	261	173	167	167
query7	4590	294	287	287
query8	242	196	196	196
query9	8510	2428	2418	2418
query10	442	252	261	252
query11	14655	14331	14172	14172
query12	138	91	88	88
query13	1641	374	378	374
query14	10101	8314	7321	7321
query15	257	161	165	161
query16	8097	270	261	261
query17	1863	569	538	538
query18	2085	291	269	269
query19	206	145	142	142
query20	94	83	82	82
query21	194	130	126	126
query22	5130	4858	4898	4858
query23	34085	33763	33452	33452
query24	11990	2920	2919	2919
query25	647	363	358	358
query26	1743	157	151	151
query27	3026	323	311	311
query28	7713	2056	2017	2017
query29	1009	606	581	581
query30	310	145	151	145
query31	954	763	748	748
query32	93	53	60	53
query33	741	247	247	247
query34	1050	505	491	491
query35	802	691	679	679
query36	1078	934	915	915
query37	267	66	66	66
query38	2924	2777	2813	2777
query39	1641	1554	1543	1543
query40	275	121	120	120
query41	42	40	38	38
query42	105	93	97	93
query43	597	537	557	537
query44	1171	723	721	721
query45	278	257	253	253
query46	1073	705	695	695
query47	2005	1904	1878	1878
query48	374	295	295	295
query49	1196	386	420	386
query50	761	387	376	376
query51	6924	6820	6840	6820
query52	103	90	92	90
query53	348	276	284	276
query54	905	428	433	428
query55	75	71	71	71
query56	234	215	217	215
query57	1260	1149	1176	1149
query58	220	203	204	203
query59	3537	3022	3175	3022
query60	260	239	240	239
query61	89	89	88	88
query62	662	464	498	464
query63	331	282	275	275
query64	9706	7381	7358	7358
query65	3142	3600	3105	3105
query66	1375	349	351	349
query67	15295	14915	14893	14893
query68	4540	529	575	529
query69	463	295	302	295
query70	1125	1171	1083	1083
query71	410	262	264	262
query72	7141	2529	2373	2373
query73	701	334	325	325
query74	6463	6169	6134	6134
query75	3300	2646	2597	2597
query76	2583	1028	965	965
query77	407	262	264	262
query78	10578	10187	10177	10177
query79	2229	504	501	501
query80	953	440	430	430
query81	506	222	217	217
query82	700	91	95	91
query83	252	166	168	166
query84	237	88	85	85
query85	1831	321	257	257
query86	488	268	310	268
query87	3268	3115	3101	3101
query88	4053	2396	2414	2396
query89	459	368	386	368
query90	1995	185	182	182
query91	126	95	106	95
query92	60	46	50	46
query93	2135	501	494	494
query94	1202	187	187	187
query95	388	294	309	294
query96	585	270	265	265
query97	3162	2985	2964	2964
query98	237	229	210	210
query99	1147	925	902	902
Total cold run time: 286448 ms
Total hot run time: 186643 ms

@doris-robot
Copy link

TeamCity be ut coverage result:
Function Coverage: 35.66% (8986/25201)
Line Coverage: 27.32% (74272/271840)
Region Coverage: 26.56% (38388/144552)
Branch Coverage: 23.37% (19572/83752)
Coverage Report: http://coverage.selectdb-in.cc/coverage/ad2ec97ea3f33e8946b46e9a13cf3f4b10451852_ad2ec97ea3f33e8946b46e9a13cf3f4b10451852/report/index.html

yiguolei
yiguolei previously approved these changes May 11, 2024
@github-actions github-actions bot added the approved Indicates a PR has been approved by one committer. label May 11, 2024
Copy link
Contributor

PR approved by at least one committer and no changes requested.

Copy link
Contributor

PR approved by anyone and no changes requested.

@jacktengg
Copy link
Contributor Author

run external

@@ -315,7 +315,8 @@ bool PipelineTask::should_revoke_memory(RuntimeState* state, int64_t revocable_m
wg->check_mem_used(&is_wg_mem_low_water_mark, &is_wg_mem_high_water_mark);
if (is_wg_mem_high_water_mark) {
if (revocable_mem_bytes > min_revocable_mem_bytes) {
LOG_EVERY_N(INFO, 10) << "revoke memory, hight water mark";
LOG_EVERY_T(INFO, 1) << "query " << print_id(state->query_id())
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

Not print this log, it should be a debug log.
This method is called by pipeline engine during every loop.
If there is many queries, the log is too often.

@@ -201,7 +201,7 @@ void WorkloadGroupMgr::refresh_wg_memory_info() {
PrettyPrinter::print(process_mem_used, TUnit::BYTES),
PrettyPrinter::print(sys_mem_available, TUnit::BYTES),
PrettyPrinter::print(all_queries_mem_used, TUnit::BYTES));
VLOG_EVERY_N(1, 10) << debug_msg;
LOG_EVERY_T(INFO, 1) << debug_msg;
Copy link
Contributor

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

very 10 seconds

@jacktengg
Copy link
Contributor Author

run buildall

@github-actions github-actions bot removed the approved Indicates a PR has been approved by one committer. label May 11, 2024
Copy link
Contributor

clang-tidy review says "All clean, LGTM! 👍"

@doris-robot
Copy link

TeamCity be ut coverage result:
Function Coverage: 35.65% (8985/25201)
Line Coverage: 27.33% (74288/271840)
Region Coverage: 26.56% (38387/144556)
Branch Coverage: 23.37% (19575/83752)
Coverage Report: http://coverage.selectdb-in.cc/coverage/4250b140d95d3f381615b1080748866fa17d2dda_4250b140d95d3f381615b1080748866fa17d2dda/report/index.html

@doris-robot
Copy link

TPC-H: Total hot run time: 41897 ms
machine: 'aliyun_ecs.c7a.8xlarge_32C64G'
scripts: https://github.com/apache/doris/tree/master/tools/tpch-tools
Tpch sf100 test result on commit 4250b140d95d3f381615b1080748866fa17d2dda, data reload: false

------ Round 1 ----------------------------------
q1	17609	4850	4257	4257
q2	2016	193	184	184
q3	10492	1285	1170	1170
q4	10201	762	823	762
q5	7467	2737	2700	2700
q6	234	131	135	131
q7	1039	643	615	615
q8	9229	2179	2097	2097
q9	9127	6700	6811	6700
q10	9229	3890	3846	3846
q11	446	253	250	250
q12	501	218	217	217
q13	17195	3163	3271	3163
q14	292	257	215	215
q15	518	469	481	469
q16	475	382	413	382
q17	1001	727	748	727
q18	8386	7770	7737	7737
q19	5766	1576	1539	1539
q20	635	341	318	318
q21	5444	4132	4322	4132
q22	368	286	288	286
Total cold run time: 117670 ms
Total hot run time: 41897 ms

----- Round 2, with runtime_filter_mode=off -----
q1	4595	4416	4427	4416
q2	372	274	268	268
q3	3137	2998	2819	2819
q4	1914	1620	1677	1620
q5	5504	5526	5520	5520
q6	219	126	122	122
q7	2354	1958	2013	1958
q8	3217	3378	3385	3378
q9	8688	8728	8529	8529
q10	3959	3870	3880	3870
q11	580	472	495	472
q12	815	617	647	617
q13	16187	3209	3100	3100
q14	288	262	267	262
q15	518	481	465	465
q16	464	439	426	426
q17	1799	1477	1460	1460
q18	7570	7466	7345	7345
q19	1681	1494	1508	1494
q20	1977	1788	1773	1773
q21	8793	4785	4887	4785
q22	597	485	516	485
Total cold run time: 75228 ms
Total hot run time: 55184 ms

@doris-robot
Copy link

TPC-DS: Total hot run time: 187430 ms
machine: 'aliyun_ecs.c7a.8xlarge_32C64G'
scripts: https://github.com/apache/doris/tree/master/tools/tpcds-tools
TPC-DS sf100 test result on commit 4250b140d95d3f381615b1080748866fa17d2dda, data reload: false

query1	921	367	354	354
query2	6456	2444	2523	2444
query3	6650	212	212	212
query4	23159	21306	21273	21273
query5	4105	422	413	413
query6	260	175	181	175
query7	4580	300	299	299
query8	245	202	191	191
query9	8465	2399	2403	2399
query10	438	253	274	253
query11	15127	14240	14089	14089
query12	134	90	90	90
query13	1641	369	375	369
query14	9008	7710	8460	7710
query15	246	171	178	171
query16	8104	271	270	270
query17	1751	583	551	551
query18	2106	285	275	275
query19	204	157	154	154
query20	98	88	89	88
query21	198	125	128	125
query22	5092	4879	4887	4879
query23	34212	33554	33576	33554
query24	8090	2917	2973	2917
query25	582	425	379	379
query26	687	154	151	151
query27	2141	310	330	310
query28	5565	2064	2064	2064
query29	837	618	584	584
query30	229	150	158	150
query31	972	752	743	743
query32	89	51	52	51
query33	553	264	246	246
query34	880	477	470	470
query35	777	684	671	671
query36	1065	936	939	936
query37	98	66	64	64
query38	2906	2785	2751	2751
query39	1644	1698	1546	1546
query40	195	123	122	122
query41	40	39	37	37
query42	102	98	96	96
query43	564	561	542	542
query44	1047	717	716	716
query45	266	244	257	244
query46	1099	711	721	711
query47	1974	1866	1922	1866
query48	372	289	298	289
query49	856	404	400	400
query50	773	398	375	375
query51	6910	6768	6786	6768
query52	102	98	87	87
query53	350	276	276	276
query54	589	423	429	423
query55	73	71	72	71
query56	248	213	225	213
query57	1219	1153	1158	1153
query58	214	201	196	196
query59	3500	3163	3282	3163
query60	258	228	231	228
query61	87	88	111	88
query62	622	467	490	467
query63	308	278	292	278
query64	8454	7324	7351	7324
query65	3133	3090	3051	3051
query66	795	346	345	345
query67	15369	15110	14895	14895
query68	4492	532	529	529
query69	477	306	310	306
query70	1171	1071	1154	1071
query71	357	271	260	260
query72	7526	2522	2354	2354
query73	709	332	326	326
query74	6445	6116	6151	6116
query75	3341	2641	2588	2588
query76	2335	997	1008	997
query77	389	265	260	260
query78	10698	10328	10177	10177
query79	2259	504	558	504
query80	1218	421	429	421
query81	524	227	218	218
query82	682	95	88	88
query83	243	193	166	166
query84	238	87	86	86
query85	2036	278	260	260
query86	494	318	324	318
query87	3308	3141	3147	3141
query88	4264	2418	2428	2418
query89	467	379	372	372
query90	2030	188	183	183
query91	124	96	96	96
query92	63	48	49	48
query93	2581	507	501	501
query94	1266	184	192	184
query95	383	307	310	307
query96	592	268	266	266
query97	3180	2968	2978	2968
query98	246	218	212	212
query99	1139	960	894	894
Total cold run time: 275111 ms
Total hot run time: 187430 ms

Copy link
Member

@mrhhsg mrhhsg left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

LGTM

@github-actions github-actions bot added the approved Indicates a PR has been approved by one committer. label May 12, 2024
Copy link
Contributor

PR approved by at least one committer and no changes requested.

Copy link
Contributor

@HappenLee HappenLee left a comment

Choose a reason for hiding this comment

The reason will be displayed to describe this comment to others. Learn more.

LGTM

@HappenLee HappenLee merged commit 8a532e8 into apache:master May 12, 2024
25 of 29 checks passed
M1saka2003 pushed a commit to M1saka2003/doris that referenced this pull request May 14, 2024
ByteYue pushed a commit to ByteYue/doris that referenced this pull request May 15, 2024
M1saka2003 pushed a commit to M1saka2003/doris that referenced this pull request May 24, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
approved Indicates a PR has been approved by one committer. reviewed
Projects
None yet
Development

Successfully merging this pull request may close these issues.

None yet

5 participants