Easy CPU Profiling

My LNUG slides from the 26th Feburary 2014, Easy and hardcore CPU profiling in #nodejs

Transcript

1. Hello!
2. Tom Gallacher ! Aka: @tomgco
3. Tom Gallacher ! Aka: @tomgco Me!
4. image by @substack Easy CPU profiling
   in node
5. What to expect
6. What to expect
7. What to expect ! function init(cb) { for (var i = 1e8 - 1; i >= 0; i--) { var a = 16 + 87 a = a - 10 } cb() }
8. What to expect
9. var http = require(‘http') ! http.createServer(function (req, res) { ! res.writeHead(200, {'Content-Type': ‘text/plain’}) ! res.end('Hello World\n') ! }).listen(1337, '127.0.0.1') ! console.log('Server running at http://127.0.0.1:1337/')
11. What?
12. $ dtrace -n 'profile-97/pid == 1337 && arg1/{ @[jstack(150, 8000)] = count(); } tick-60s { exit(0); }' > stacks.out $ stackvis dtrace flamegraph-svg < stacks.out > stacks.svg $ npm install -g stackvis Flame-graph using stack-vis
    
14. Illumos-based system required Requires sudo (utilises dtrace) Understanding of libuv required Downfalls:
    Well not really, it can help
15. What about SaaS’s?
16. StrongOps $ npm install -g strong-cli $ open http://strongloop.com/ops/dashboard $ slc strongops --register $ slc example $ cd sls-sample-app
18. NodeTime $ npm install --save nodetime $ node -e 'require('nodetime').profile( { accountKey: your_account_key })' $ open https://nodetime.com/apps
20. Don’t fancy sending data to third parties? $ npm install -g webkit-devtools-agent $ npm install -g bugger $ npm install -g node-inspector -- Upcoming --
22. PAIN!
23. v0.11
24. $ npm install --save-dev cpu-profiler $ npm install --save-dev chrome-cpu-profiler Roll your own
    (thats what I did)
25. CPU-Profiler Originally by Danny Coates - https://github.com/dannycoates Updated by StrongLoop - https://github.com/strongloop New features by me! - https://github.com/tomgco
26. CPU-Profiler var profiler = require('cpu-profiler') profiler.startProfiling([name], true) var cpuProfile = profiler.stopProfiling([name]) // …suspected code bottleneck to profile
27. CPU-Profiler { samplesCount: 0 , endTime: 1393368793528908 , startTime: 1393368789344919 , topRoot: { scriptId: 0 , hitCount: 0 , childrenCount: 2 , id: 1 , callUid: 2833936552 , lineNumber: 0 , bailoutReason: '' , scriptName: '' , functionName: '(root)' , getChild: [Function: getChild] } , uid: 1 , title: 'a' , delete: [Function: delete] , getSample: [Function: getSample] }
28. Chrome-CPU-Profiler var profiler = require('chrome-cpu-profiler') profiler.startProfiling('cpu-block') var data = profiler.stopProfiling('cpu-block') // …suspected code bottleneck to profile profiler.writeFile(data)
34. USES
35. image by @substack Easy CPU profiling
    in node
36. V8 Tools $ node --prof app.js $ git clone git@github.com:v8/v8.git $ ./tools/mac-tick-processor ~/v8.log > out.prof $ cat out.prof $ cd v8; git checkout 3.20.14.1 #or relevant v8 version
37. Statistical profiling result from v8.log, (395 ticks, 1 unaccounted, 0 excluded). ! [JavaScript]: ticks total nonlib name 181 45.8% 61.6% LazyCompile: *init /Users/tomg/Projects/nodejs/chrome-cpu-profiler/ 101 25.6% 34.4% LazyCompile: *initInner /Users/tomg/Projects/nodejs/chrome-cpu-prof 2 0.5% 0.7% RegExp: ^(\\/?|)([\\s\\S]*?)((?:\\.{1\,2}|[^\\/]+?|)(\\.[^.\\/]*|)) 2 0.5% 0.7% LazyCompile: *pre /Users/tomg/Projects/nodejs/chrome-cpu-profiler/e 1 0.3% 0.3% Stub: KeyedStoreElementStub 1 0.3% 0.3% Stub: FunctionPrototypeStub 1 0.3% 0.3% Stub: BinaryOpStub_ADD_ReuseLeft(String*String->String) 1 0.3% 0.3% LazyCompile: ~substr native string.js:748:22 1 0.3% 0.3% LazyCompile: ~Buffer.isEncoding buffer.js:126:29 1 0.3% 0.3% KeyedCallMegamorphic: args_count: 2 1 0.3% 0.3% Builtin: ArgumentsAdaptorTrampoline
38. ! [GC]: ticks total nonlib name 3 0.8% ! [Bottom up (heavy) profile]: Note: percentage shows a share of a particular caller in the total amount of its parent calls. Callers occupying less than 2.0% are not shown. ! ticks parent name 181 45.8% LazyCompile: *init /Users/tomg/Projects/nodejs/chrome-cpu-profiler/example/cp 91 50.3% LazyCompile: ~preInner /Users/tomg/Projects/nodejs/chrome-cpu-profiler/exam 91 100.0% LazyCompile: *pre /Users/tomg/Projects/nodejs/chrome-cpu-profiler/example 91 100.0% LazyCompile: ~bootstrap /Users/tomg/Projects/nodejs/chrome-cpu-profiler 91 100.0% Function: ~<anonymous> /Users/tomg/Projects/nodejs/chrome-cpu-profile 91 100.0% LazyCompile: ~Module._compile module.js:367:37 90 49.7% LazyCompile: ~preInner /Users/tomg/Projects/nodejs/chrome-cpu-profiler/exam 90 100.0% LazyCompile: *pre /Users/tomg/Projects/nodejs/chrome-cpu-profiler/example 90 100.0% LazyCompile: ~initInner /Users/tomg/Projects/nodejs/chrome-cpu-profiler 90 100.0% LazyCompile: *init /Users/tomg/Projects/nodejs/chrome-cpu-profiler/ex 90 100.0% LazyCompile: ~preInner /Users/tomg/Projects/nodejs/chrome-cpu-profi ! 101 25.6% LazyCompile: *initInner /Users/tomg/Projects/nodejs/chrome-cpu-profiler/examp 101 100.0% LazyCompile: *init /Users/tomg/Projects/nodejs/chrome-cpu-profiler/example/ 101 100.0% LazyCompile: ~preInner /Users/tomg/Projects/nodejs/chrome-cpu-profiler/ex 101 100.0% LazyCompile: *pre /Users/tomg/Projects/nodejs/chrome-cpu-profiler/examp 101 100.0% LazyCompile: ~initInner /Users/tomg/Projects/nodejs/chrome-cpu-profil 101 100.0% LazyCompile: *init /Users/tomg/Projects/nodejs/chrome-cpu-profiler/
39. " " (compiled) "~" (optimisable) "*" (optimised)
40. :O
41. Production
42. Dtrace $ npm install --save dtrace-provider var d = require('dtrace-provider') ! var dtp = d.createDTraceProvider('nodeapp') var p1 = dtp.addProbe('probe1', 'int', 'int') var p2 = dtp.addProbe('probe2', 'char *') dtp.enable()
43. Metrics - StatsD - Graphite - Lynx https://github.com/dscape/lynx $ npm install --save lynx
46. Tom Gallacher ! Aka: @tomgco
47. Q&A
48. YAY
49. BYE!