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

[Bug] discrepancies between auditlog and profile reported numbers #51646

Open
dengliu opened this issue Oct 9, 2024 · 2 comments
Open

[Bug] discrepancies between auditlog and profile reported numbers #51646

dengliu opened this issue Oct 9, 2024 · 2 comments
Labels
type/bug Something isn't working

Comments

@dengliu
Copy link
Contributor

dengliu commented Oct 9, 2024

Steps to reproduce the behavior (Required)

understand discrepancies between auditlog and profile reported numbers:

Select query:
------------
From auditlog:

CpuCostNs
1915775920858
31.93 min
does not match anything in profile

MemCostBytes
12545335920
11.68 GB
matches QueryPeakMemoryUsagePerNode

Time
2336
2.336 sec
matches QueryExecutionWallTime

From profile:

  Execution:
     - FrontendProfileMergeTime: 79.260ms
     - QueryAllocatedMemoryUsage: 2.590 TB
     - QueryCumulativeCpuTime: 1h54m
     - QueryCumulativeNetworkTime: 196.241ms
     - QueryCumulativeOperatorTime: 8s131ms
     - QueryCumulativeScanTime: 2s256ms
     - QueryDeallocatedMemoryUsage: 2.589 TB
     - QueryExecutionWallTime: 2s324ms
     - QueryPeakMemoryUsagePerNode: 11.684 GB
     - QueryPeakScheduleTime: 944.846ms
     - QuerySpillBytes: 0.000 B
     - QuerySumMemoryUsage: 873.853 GB
     - ResultDeliverTime: 0ns
Update query
------------
auditlog:

cpuCostNs
39228243805444
10.9h
matches QueryCumulativeCpuTime

memCostBytes
1048626061752
976.61GiB
matches QuerySumMemoryUsage

Time
114874
114.87s
does not match with profile

Execution:
     - FrontendProfileMergeTime: 21.210ms
     - QueryAllocatedMemoryUsage: 9.067 TB
     - QueryCumulativeCpuTime: 10h53m
     - QueryCumulativeNetworkTime: 0ns
     - QueryCumulativeOperatorTime: 1m40s
     - QueryCumulativeScanTime: 0ns
     - QueryDeallocatedMemoryUsage: 9.052 TB
     - QueryExecutionWallTime: 1m48s
     - QueryPeakMemoryUsagePerNode: 52.847 GB
     - QueryPeakScheduleTime: 57.050ms
     - QuerySpillBytes: 0.000 B
     - QuerySumMemoryUsage: 976.609 GB
     - ResultDeliverTime: 2s827ms

Expected behavior (Required)

Introduce two metrics: peak

Real behavior (Required)

Make the metrics from audit log consistent with the metrics of the profile

StarRocks version (Required)

N/A

@dengliu dengliu added the type/bug Something isn't working label Oct 9, 2024
@jaogoy
Copy link
Contributor

jaogoy commented Nov 11, 2024

So, the difference is as below?

CpuCostNs
1915775920858
31.93 min
does not match anything in profile

And are there any other queries with different Query profile and Audit log?

@dengliu
Copy link
Contributor Author

dengliu commented Nov 11, 2024

So, the difference is as below?

CpuCostNs
1915775920858
31.93 min
does not match anything in profile

And are there any other queries with different Query profile and Audit log?

@hagonzal Do you have more data point?

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
type/bug Something isn't working
Projects
None yet
Development

No branches or pull requests

2 participants