1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
259
260
261
262
263
264
265
266
267
268
269
270
271
272
273
274
275
276
277
278
279
280
281
282
283
284
285
286
287
288
289
290
291
292
293
294
295
296
297
298
299
300
|
#!/usr/bin/env python3
'''
_/_/ _/
_/ _/ _/_/ _/ _/_/ _/_/_/ _/_/_/
_/_/_/_/ _/_/_/_/ _/_/ _/ _/ _/ _/ _/
_/ _/ _/ _/ _/ _/ _/ _/ _/
_/ _/ _/_/_/ _/ _/_/_/ _/ _/_/_/
_/
_/_/
Copyright:
This program is free software: you can redistribute it
and/or modify it under the terms of the GNU General
Public License as published by the Free Software
Foundation, either version 3 of the License, or (at your
option) any later version.
This program is distributed in the hope that it will be
useful, but WITHOUT ANY WARRANTY; without even the implied
warranty of MERCHANTABILITY or FITNESS FOR A PARTICULAR
PURPOSE. See the GNU General Public License for more details.
You should have received a copy of the GNU General Public
License along with this program. If not, see
<https://www.gnu.org/licenses/>.
Commentary:
Aergia is a sampling based profiler based off of SCALENE
by Emery Berger (https://github.com/plasma-umass/scalene).
It is not particularly informative, but unlike SCALENE
or other sampling-based profilers I could find, reports
the wall-time each asyncio await call spends idling.
The goal behind Aergia is to eventually have these features,
or similar, merged into SCALENE.
Code:
'''
from collections import defaultdict
from types import FrameType
from typing import cast, List, Tuple
import argparse
import asyncio
import selectors
import signal
import sys
import threading
import time
import traceback
class ReplacementEpollSelector(selectors.EpollSelector):
'''
Provides a replacement for selectors.PollSelector that
periodically wakes up to accept signals.
'''
def select(
self, timeout=None
) -> List[Tuple[selectors.SelectorKey, int]]:
start_time = time.perf_counter()
if not timeout or timeout < 0:
interval = sys.getswitchinterval()
else:
interval = min(timeout, sys.getswitchinterval())
while True:
selected = super().select(interval)
if selected or timeout == 0 or not timeout:
return selected
end_time = time.perf_counter()
if end_time - start_time >= timeout:
return []
selectors.DefaultSelector = ReplacementEpollSelector
class Aergia(object):
# a key-value pair where keys represent frame metadata (see
# Aergia.frame_to_string) and values represent number of times
# sampled.
cpu_samples = defaultdict(lambda: 0)
cpu_samples_c = defaultdict(lambda: 0)
# number of times samples have been collected
total_cpu_samples = 0
# the time, in seconds, between samples
signal_interval = 0.01
# the timestamp recorded last signal
last_signal_time = 0.0
def __init__(self):
signal.signal(signal.SIGPROF,
self.cpu_signal_handler)
signal.setitimer(signal.ITIMER_PROF,
self.signal_interval,
self.signal_interval)
@staticmethod
def gettime():
'''get the wallclock time'''
return time.time()
@staticmethod
def start(profile_async):
Aergia.last_signal_time = Aergia.gettime()
@staticmethod
def stop():
'''Turn off profiling signals'''
Aergia.disable_signals()
Aergia.exit_handler()
@staticmethod
def exit_handler():
'''Pretty-print profiling information.'''
# If we've collected any samples, dump them.
print("CPU usage (Python):")
if Aergia.total_cpu_samples > 0:
for key in Aergia.sort_samples(Aergia.cpu_samples):
print(f"{key} : "
f"{Aergia.cpu_samples[key] * 100 / Aergia.total_cpu_samples:.3f} % "
f"({Aergia.cpu_samples[key]:.1f} total samples)")
print("CPU usage (Native):")
for key in Aergia.sort_samples(Aergia.cpu_samples_c):
print(f"{key} : "
f"{Aergia.cpu_samples_c[key] * 100 / Aergia.total_cpu_samples:.3f} % "
f"({Aergia.cpu_samples_c[key]:.1f} total samples)")
else:
print("(Bug) The program did not run long enough to profile, or a one-time error occured.")
@staticmethod
def disable_signals():
signal.signal(signal.ITIMER_PROF, signal.SIG_IGN)
signal.signal(signal.SIGVTALRM, signal.SIG_IGN)
signal.setitimer(signal.ITIMER_PROF, 0)
@staticmethod
def cpu_signal_handler(sig, frame):
elapsed_since_last_signal = Aergia.gettime() - \
Aergia.last_signal_time
c_time_norm = (elapsed_since_last_signal -
Aergia.signal_interval) / \
Aergia.signal_interval
keys = Aergia.compute_frames_to_record()
for key in keys:
Aergia.cpu_samples[Aergia.frame_to_string(key)] += 1
Aergia.cpu_samples_c[Aergia.frame_to_string(
key)] += c_time_norm
Aergia.total_cpu_samples += elapsed_since_last_signal / \
Aergia.signal_interval
Aergia.last_signal_time = Aergia.gettime()
@staticmethod
def compute_frames_to_record():
'''Collects all stack frames that Aergia actually processes.'''
if Aergia.is_event_loop_running():
frames = [task.get_coro().cr_frame for task in asyncio.all_tasks()]
# Process all the frames to remove ones we aren't going to track.
new_frames = []
for frame in frames:
if frame is None:
continue
fname = frame.f_code.co_filename
# Record samples only for files we care about.
if (len(fname)) == 0:
# 'eval/compile' gives no f_code.co_filename. We have
# to look back into the outer frame in order to check
# the co_filename.
fname = frame.f_back.f_code.co_filename
while not Aergia.should_trace(fname):
# Walk the stack backwards until we hit a frame that
# IS one we should trace (if there is one). i.e., if
# it's in the code being profiled, and it is just
# calling stuff deep in libraries.
if frame:
frame = cast(FrameType, frame.f_back)
else:
break
if frame:
fname = frame.f_code.co_filename
if frame:
new_frames.append(frame)
return new_frames
@staticmethod
def frame_to_string(frame):
'''Pretty-prints a frame as a function/file name and a line number.
Additionally used a key for tallying lines.'''
co = frame.f_code
func_name = co.co_name
line_no = frame.f_lineno
filename = co.co_filename
return filename + '\t' + func_name + '\t' + str(line_no)
@staticmethod
def should_trace(filename):
'''Returns FALSE if filename is uninteresting to the user.'''
# FIXME Assume GuixSD. Makes filtering easy
if 'site-packages' in filename:
return False
if filename[0] == '<':
return False
if 'aergia' in filename:
return False
return True
@staticmethod
def is_event_loop_running() -> bool:
'''Returns TRUE if there is an exent loop running. This is what
`asyncio.get_event_loop()' did, before it was deprecated in 3.12'''
return asyncio.get_event_loop_policy()._local._loop is not None
@staticmethod
def sort_samples(sample_dict):
'''Returns SAMPLE_DICT in descending order by number of samples.'''
return {k: v for k, v in sorted(sample_dict.items(),
key=lambda item: item[1],
reverse=True)}
@staticmethod
def filter_duplicated_frames(frames) -> bool:
s = set()
dup = []
for f in frames:
if f in s:
dup.append(f)
else:
s.add(f)
# TODO we probably have one because given get_async_frames returns the
# currently executing task. Would be an easy fix in that method.
# if there's more than one, I cannot explain it.
assert len(
dup) < 2, f"ERROR: More than 1 duplicate frame (shouldn't happen): {dup}"
if len(dup) != 0:
print(f"WARN: Duplicate frame found: {dup}", file=sys.stderr)
return list(s)
the_globals = {
'__name__': '__main__',
'__doc__': None,
'__package__': None,
'__loader__': globals()['__loader__'],
'__spec__': None,
'__annotations__': {},
'__builtins__': globals()['__builtins__'],
'__file__': None,
'__cached__': None,
}
def parse_arguments():
'''Parse CLI args'''
parser = argparse.ArgumentParser(
usage='%(prog)s [args] script [args]'
)
parser.add_argument('-a', '--async_off',
action='store_false',
help='Turn off experimental async profiling.',
default=True)
parser.add_argument('-i', '--interval',
help='The minimum amount of time inbetween \
samples in seconds.',
metavar='',
default=0.01)
parser.add_argument('script', help='A python script to run.')
parser.add_argument('s_args', nargs=argparse.REMAINDER,
help='python script args')
return parser.parse_args()
def main():
args = parse_arguments()
sys.argv = [args.script] + args.s_args
try:
with open(args.script, 'rb') as fp:
code = compile(fp.read(), args.script, "exec")
Aergia().start(args.async_off)
exec(code, the_globals)
Aergia().stop()
except Exception:
traceback.print_exc()
if __name__ == "__main__":
main()
|