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
|
if not modules then modules = { } end modules ['mtx-profile'] = {
version = 1.000,
comment = "companion to mtxrun.lua",
author = "Hans Hagen, PRAGMA-ADE, Hasselt NL",
copyright = "PRAGMA ADE / ConTeXt Development Team",
license = "see context related readme files"
}
-- todo: also line number
-- todo: sort runtime as option
-- todo: make it more efficient .. real old code
local match, format, find, gsub = string.match, string.format, string.find, string.gsub
local helpinfo = [[
<?xml version="1.0"?>
<application>
<metadata>
<entry name="name">mtx-profile</entry>
<entry name="detail">ConTeXt MkIV LuaTeX Profiler</entry>
<entry name="version">1.00</entry>
</metadata>
<flags>
<category name="basic">
<subcategory>
<flag name="analyze"><short>analyze lua calls</short></flag>
<flag name="trace"><short>analyze tex calls</short></flag>
</subcategory>
</category>
</flags>
</application>
]]
local application = logs.application {
name = "mtx-profile",
banner = "ConTeXt MkIV LuaTeX Profiler 1.00",
helpinfo = helpinfo,
}
local report = application.report
scripts = scripts or { }
scripts.profiler = scripts.profiler or { }
local timethreshold = 0
local callthreshold = 2500
local countthreshold = 2500
local functiontemplate = "%12s %03.4f %9i %s"
local calltemplate = "%9i %s"
local totaltemplate = "%i internal calls, %i function calls taking %3.4f seconds"
local thresholdtemplate = "thresholds: %i internal calls, %i function calls, %i seconds"
function scripts.profiler.analyze(filename)
local f = io.open(filename)
if f then
local times, counts, calls = { }, { }, { }
local totalruntime, totalcount, totalcalls = 0, 0, 0
for line in f:lines() do
if not find(line,"__index") and not find(line,"__newindex") then
local stacklevel, filename, functionname, linenumber, currentline, localtime, totaltime = match(line,"^(%d+)\t(.-)\t(.-)\t(.-)\t(.-)\t(.-)\t(.-)")
if not filename then
-- next
elseif filename == "=[C]" then
if not functionname:find("^%(") then
calls[functionname] = (calls[functionname] or 0) + 1
end
else
local filename = match(filename,"^@(.*)$")
if filename then
local fi = times[filename]
if not fi then fi = { } times[filename] = fi end
fi[functionname] = (fi[functionname] or 0) + tonumber(localtime)
counts[functionname] = (counts[functionname] or 0) + 1
end
end
end
end
f:close()
print("")
local loaded = { }
local sorted = table.sortedkeys(times)
for i=1,#sorted do
local filename = sorted[i]
local functions = times[filename]
local sorted = table.sortedkeys(functions)
for i=1,#sorted do
local functionname = sorted[i]
local totaltime = functions[functionname]
local count = counts[functionname]
totalcount = totalcount + count
if totaltime > timethreshold or count > countthreshold then
totalruntime = totalruntime + totaltime
local functionfile, somenumber = match(functionname,"^@(.+):(.-)$")
if functionfile then
local number = tonumber(somenumber)
if number then
if not loaded[functionfile] then
loaded[functionfile] = string.splitlines(io.loaddata(functionfile) or "")
end
functionname = loaded[functionfile][number] or functionname
functionname = gsub(functionname,"^%s*","")
functionname = gsub(functionname,"%s*%-%-.*$","")
functionname = number .. ": " .. functionname
end
end
filename = file.basename(filename)
print(format(functiontemplate,filename,totaltime,count,functionname))
end
end
end
print("")
local sorted = table.sortedkeys(calls)
for i=1,#sorted do
local call = sorted[i]
local n = calls[call]
totalcalls = totalcalls + n
if n > callthreshold then
print(calltemplate:format(n,call))
end
end
print("")
print(totaltemplate:format(totalcalls,totalcount,totalruntime))
print("")
print(thresholdtemplate:format(callthreshold,countthreshold,timethreshold))
end
end
function scripts.profiler.x_analyze(filename)
local f = io.open(filename)
local calls = { }
local lines = 0
if f then
while true do
local line = f:read()
if line then
lines = lines + 1
local c = match(line,"\\([a-zA-Z%!%?@]+) *%->")
if c then
local cc = calls[c]
if not cc then
calls[c] = 1
else
calls[c] = cc + 1
end
end
else
break
end
end
f:close()
local noc = 0
local criterium = 100
for name, n in next, calls do
if n > criterium then
if find(name,"^@@[a-z][a-z]") then
-- parameter
elseif find(name,"^[cvserft]%!") then
-- variables and constants
elseif find(name,"^%?%?[a-z][a-z]$") then
-- prefix
elseif find(name,"^%!%!") then
-- reserved
elseif find(name,"^@.+@$") then
-- weird
else
noc = noc + n
print(format("%6i: %s",n,name))
end
end
end
print("")
print(format("number of lines: %s",lines))
print(format("number of calls: %s",noc))
print(format("criterium calls: %s",criterium))
end
end
--~ scripts.profiler.analyze("t:/manuals/mk/mk-fonts-profile.lua")
--~ scripts.profiler.analyze("t:/manuals/mk/mk-introduction-profile.lua")
if environment.argument("analyze") then
scripts.profiler.analyze(environment.files[1] or "luatex-profile.log")
elseif environment.argument("trace") then
scripts.profiler.analyze(environment.files[1] or "temp.log")
elseif environment.argument("exporthelp") then
application.export(environment.argument("exporthelp"),environment.files[1])
else
application.help()
end
|
