6hY@SrSSKJr SSKrSSKrSSKrSSKrSSKrSSKrSSK r SSK r SSK r SSK r SSK Jr SSKJrJr \R$r\R&r/SQr1SkrS r"S S 5r\"5r"S S 5r"SS5rSr\ R8"5rSrSrSq S)Sjr!Sr"Sr#Sr$S*Sjr%\S5r&"SS5r'"SS5r(S+Sjr)Sr*Sr+S*S jr,S,S!jr-S-S"jr.0r/S#r0S$r1S.S%jr2S&r3S*S'jr4\5S(:Xa\ Rl"\4"55 gg)/a statprof is intended to be a fairly simple statistical profiler for python. It was ported directly from a statistical profiler for guile, also named statprof, available from guile-lib [0]. [0] http://wingolog.org/software/guile-lib/statprof/ To start profiling, call statprof.start(): >>> start() Then run whatever it is that you want to profile, for example: >>> import test.pystone; test.pystone.pystones() Then stop the profiling and print out the results: >>> stop() >>> display() % cumulative self time seconds seconds name 26.72 1.40 0.37 pystone.py:79:Proc0 13.79 0.56 0.19 pystone.py:133:Proc1 13.79 0.19 0.19 pystone.py:208:Proc8 10.34 0.16 0.14 pystone.py:229:Func2 6.90 0.10 0.10 pystone.py:45:__init__ 4.31 0.16 0.06 pystone.py:53:copy ... All of the numerical data is statistically approximate. In the following column descriptions, and in all of statprof, "time" refers to execution time (both user and system), not wall clock time. % time The percent of the time spent inside the procedure itself (not counting children). cumulative seconds The total number of seconds spent in the procedure, including children. self seconds The total number of seconds spent in the procedure itself (not counting children). name The name of the procedure. By default statprof keeps the data collected from previous runs. If you want to clear the collected data, call reset(): >>> reset() reset() can also be used to change the sampling frequency from the default of 1000 Hz. For example, to tell statprof to sample 50 times a second: >>> reset(50) This means that statprof will sample the call stack after every 1/50 of a second of user + system time spent running on behalf of the python process. When your process is idle (for example, blocking in a read(), as is the case at the listener), the clock does not advance. For this reason statprof is not currently not suitable for profiling io-bound operations. The profiler uses the hash of the code object itself to identify the procedures, so it won't confuse different procedures with the same name. They will show up as two different rows in the output. Right now the profiler is quite simplistic. I cannot provide call-graphs or other higher level information. What you see in the table is pretty much all there is. Patches are welcome :-) Threading --------- Because signals only get delivered to the main thread in Python, statprof only profiles the main thread. However because the time reporting function uses per-process timers, the results can be significantly off if other threads' work patterns are not similar to the main thread's work patterns. ) annotationsN)List)encodingpycompat)startstopresetdisplayprofile> util.py:checkhg.py:dispatch.py:runpager.py:pagecmdcolor.py:colorcmddispatch.py:dispatch.py:dispatchdispatch.py:_dispatchdispatch.py:_runcatchdispatch.py:checkargsextensions.py:closuredispatch.py:runcommanddispatch.py:_runcommandevolve.py:warnobserrorscL[R"5nUSUS-US4$)Nrr)ostimes)rs 4/usr/lib/python3/dist-packages/mercurial/statprof.pyclockr s) HHJE !HuQx q **cP\rSrSr%S\S'S SjrS SjrSrSr\ S 5r S r g) ProfileStatez List[Sample]samplesNc4URU5 SUlg)Ncpu)r trackself frequencys r__init__ProfileState.__init__s 9 r!cSUlSUlU(a SU- UlO[US5(dSUlOSUlSUl/Ulg)N)r/g?sample_intervalMbP?r)accumulated_timelast_start_timer0hasattrremaining_prof_time profile_levelr%r)s rr ProfileState.resetsS *# #&?D 011#/D  #'  r!cUSURS- USURS- 4nURSUS-URSUS-4UlgNrr)r3r2)r* stop_time increments raccumulate_timeProfileState.accumulate_timesq aL4//2 2 aL4//2 2  ! !! $y| 3  ! !! $y| 3! r!c`URUR[UR5- $N)r2timeidxlenr%r*s rseconds_per_sampleProfileState.seconds_per_samples%$$T\\2S5FFFr!c&URS:Xagg)Nsrealrr)r(rBs rr@ProfileState.timeidxs :: r!)r2r3r6r5r0r%r(r?) __name__ __module__ __qualname____firstlineno____annotations__r,r r<rCpropertyr@__static_attributes__r!rr#r#s1 ( Gr!r#cP\rSrSr0rSrSrSrSr\ S5r Sr Sr S r S rg ) CodeSite)pathlinenofunctionsourcec[U[5(deXlX l[U[5(deX0lSUlgr?) isinstancebytesrRrSrTrU)r*rRrSrTs rr,CodeSite.__init__s>$&&&&  (E****  r!cURUR:H=(a URUR:H$! g=f)NF)rSrR)r*others r__eq__CodeSite.__eq__s6 ;;%,,.J499 3J J s8;?cD[URUR45$r?)hashrSrRrBs r__hash__CodeSite.__hash__sT[[$)),--r!czX4nURU$![a U"XU5nXPRU'Us$f=fr?)cacheKeyError)clsrRrSrTkvs rget CodeSite.getsG N 99Q<  D(+AIIaLH s #::cURcsURS- n[URS5n[ U5H!upEXB:XdM UR 5Ul O SSS5 URcSUlURn[ U5U:a USUS- S-nU$!,(df  NJ=f! NQ=f)Nrrbr!s...)rUrSopenrR enumeratestriprA)r*lengthrSfpilinerUs r getsourceCodeSite.getsources ;;  q$))T*b#,R=;*.**,DK!$1+{{"!  v; Nvz+f4F +*  s.%B:B) B)%B:) B73B:7B::B>cT[RRUR5$r?)rrRbasenamerBs rfilenameCodeSite.filenamesww **r!cDUR5<SUR<3$)N:)rxrTrBs rskipnameCodeSite.skipname s--/4==99r!)rTrSrRrUN)rGrHrIrJrc __slots__r,r\r` classmethodrhrtrxr|rMrNr!rrPrPs> E8I .&+:r!rPc.\rSrSrSrSr\S5rSrg)Samplei stacktimecXlX lgr?r)r*rrs rr,Sample.__init__s   r!c T/nU(aUR[R[R"UR R 5UR[R"UR R555 URnU(aM[X25$r?) appendrPrhrsysbytesf_code co_filenamef_linenoco_namef_backr)reframerrs r from_frameSample.from_framesx LL %%ell&>&>?NN%%ell&:&:; LLEee""r!N) rGrHrIrJr~r,rrrMrNr!rrr s !I # #r!rc[RS:a[5n[RU5 [R[R n[R R[RX55 [R"[R[RS5 U[lgg)Nrr/)stater6r r<r2r@r%rrrsignal setitimer ITIMER_PROFr0r3)signumrnow timestamps rprofile_signal_handlerr)s Qg c"**5==9  V..u@A++U-B-BCH #r!c[R5(d[5n[R U5 [ R "5Un[R[Rn[RR[RX#55 U[l [R"[R 5 [R5(dM[R#5 gr?) stopthreadis_setr rr<sys_current_framesr2r@r%rrrr3rsleepr0clear)tidrrrs r samplerthreadr8s!!g c"##%c***5==9  V..u@A # 5(()!!r!c([RS:$Nr)rr6rNr!r is_activerLs    ""r!cU[l[=RS- sl[RS:XGa>[5[l[R nS[lUqUS:Xag[R"[R[5 [R"[RU=(d [RS5 gUS:Xa[R"5n[R "5R#5VVs/sHupEXS:XdM UPM snnSn[$R&"[(U4SS9[l[R*R-5 gggs snnf) z:Install the profiling signal handler, and start profiling.rNsignalr/threadrr)targetargsname)rr(r6r r3r5 lastmechanismrSIGPROFrrrr0inspect currentframerritems threadingThreadrthreadr) mechanismr(rptrrffrs rrrSsEK 1 a %''$(!"  ! MM&..*@ A   ""C$@5+@+@# ) #((*E!$!4!4!6!M!>!*1!>MaPC$++$C6EL LL   $ Ns  E9'E9cx[=RS-sl[RS:XGa[S:Xah[R"[R SS5n[R"[R [R5 US[lO<[S:Xa2[R5 [RR5 [R[55 S[l[ R"R%S5nU(a ['U5 [$)z;Stop profiling, and uninstall the profiling signal handler.rrrr/rNs STATPROF_DEST)rr6rrrrrSIG_IGNr5rsetrjoinr<r r3renvironrh save_data)r statprofpaths rr r ms 1 a I %""6#5#5sC@C MM&..&.. 9(+AE % i ' NN  LL    eg& $''++,<=  l # Lr!c [US5nURS[R-5 [RHnUR nUR nUVs/sHAnSRURSUR-=(d SUR/5PMC nnURSUSRU54-5 M SSS5 gs snf!,(df  g=f)Nw+bs%f %f s%ds%d%s ) rmwriterr2r%rrrrRrSrT)rRfilesamplerrssitess rrrs dE d : 6 667mmF;;DLLEA AFFEAHH$4$:AJJGH  JJ{dEJJu,=%>> ?$     sAC AC'+C C  C.c p[US5nUR5R5nSSS5 WSR5Vs/sHn[ U5PM sn[ l/[ lUSSHnURS5n[ US5nUSSn/nUHLn U RS5n UR[RU S[U S5U S55 MN [ RR[X55 M g!,(df  N=fs snf)Nrkrrrr) rmread splitlinessplitfloatrr2r%rrPrhintr) rRrqlinesvaluerspartsrrawsitesrrawsite sitepartss r load_datars dD R $$& 9>a8HI8HueEl8HIEEMab  5!U1X9G e,I LL Yq\3y|+*>a>***s B)rrrN)rGrHrIrJr,rrrrrrrrrMrNr!rrrs9 9:;<++r!rc0\rSrSrSrSrSrSrSrSr Sr S r g ) DisplayFormatsirrrrlrrNN) rGrHrIrJByLineByMethod AboutMethodHotpath FlameGraphJsonChromerMrNr!rrrs% FHKGJ D Fr!rc jUc[nUcSSKJn URn[ UR 5S:XaUR S5 gU[R:Xa [X 5 OU[R:Xa [X 5 OU[R:Xa [X 40UD6 OU[R:Xa [X 40UD6 OmU[R :Xa [#X 40UD6 OLU[R$:Xa ['X 5 O,U[R(:Xa [+X 40UD6 O [-S5eU[R$[R(4;aWUR S5 UR S[ UR 5-5 UR S UR.-5 gg) z** *$# >-- -T00 >)) )+F+ >,, ,t*6* >&& &d >(( (+F+011 n))>+@+@AA  &T\\)::; 69N9NNOBr!c[RUR5nURSSS9 UR SS-5 UR SS-5 UHnUR nSUR 5UR=(d S UR4-nUR S UR5UR5UR5U4-5 M g ) zoPrint the profiler data with each sample line represented as one row in a table. Sorted by self-time per line.Tc"UR5$r?rxs r!display_by_line..s 1==?r!reversekey%5.5s %10.10s %7.7s %-8.8s s% s cumulativesselfr!%5.5s %9.9s %8.8s %-8.8s stimesecondsr$snames%s:%d:%sr%6.2f %9.2f %9.2f %s N) rrr%sortrrrxrSrTrrr)rrqrstatr sitelabels rr r s   .E JJt!:J;HH* / 0HH( 4 5 yy MMO KK 2 MM#   &  "!!#  "   r!c hURSS-5 URSS-5 [RUR5n[ [ 5nUHHnX4R R5S-UR R-RU5 MJ /nUR5H^upgSnSn Sn UH9nXR5- nXR5- n XR5- n M; URXhXU45 M` URSSS 9 UGHn U S S :aMURS U S U S U SU S4-5 U SRSSS 9 U SHnUR5S :dMUR RS5n [!U ["5(d[$R&"U 5n UR5UR5UR R(=(d SU 4n URSU -5 M GM g)zPrint the profiler data with each sample function represented as one row in a table. Important lines within that function are output as nested rows. Sorted by self-time per line.r r!r"r#:rTc US$)NrrNrs rr#display_by_method..Ys!A$r!rrl皙?r%rrrc"UR5$r?r)rrs rrr,hs Q]]_r!rs%33.0f%% %6.2f line %d: %s N)rrrr% defaultdictlistrrxrTrrrrrr&rtrWrXrbytestrrS)rrqrgroupedr' functiondatafname sitestats total_cum_sectotal_self_sec total_percentrTrU stattuples rr r 6s'HH* / 0HH( 4 5   .E$G ""$t+dii.@.@@AHHNL#MMO  D ..0 0M ..0 0N --/ /M  >) L ,d7  A;    &        +DEQKD!A%,,R0!&%00%--f5F$$&$$&II$$*  :YFG !r!c Uc [S5eSnSU;aURS5upBSn0n0nURHn[UR5HupU R U:XdMU(aU R 5U:XdM4US- nU [UR5S- :wa%URU S-n X;a XkS-Xk'OSXk'X;a XzS-Xz'MSXz'M M UR5V V s/sHupX4PM nn n URSSS9 UHupURSX- S -[R"U R 55[R"U R 5U R=(d S [R"U RS 554-5 M [ R#UR5n U Vs/sHHnUR$R U:XdMU(a UR$R 5U:XdMFUPMJ n nSnSnSnSnU HOnUUR'5- nUUR)5- nUUR+5- nUUR-5- nMQ URS [R"U=(d S 5[R"U5UUUU4-5 UR5VV s/sH unn UU 4PM nnn URSSS9 UHXunn URSX- S -UR=(d S [R"URS 554-5 MZ gs sn n fs snfs sn nf)NzInvalid functionr*rrTc US$rrNrs rr&display_about_method..sQqTr!rs%6.2f%% %s:%s line %s: %s rr2sA %s:%s Total: %0.2fs (%0.2f%%) Self: %0.2fs (%0.2f%%) s___c US$rrNrs rrr=sadr!s %6.2f%% line %s: %s )rrr%rnrrTrxrArr&rrfsencoderrSrtrrrrrrr)rrqrTrrxrelevant_samplesparentschildrenrrrrparentcountrrr7r8total_self_percenttotal_cum_percentr'childs rr r zsP*++H x%^^D1GH,, .GA}}( 8 ; A% FLL)A--#\\!a%0F(*1/A*=*+#%-^a%7HN%&HN/$5L2   .(3.!!&//"34!!&//2 #!!&"2"22"67   !   .E A 66??h & QVV__.(:   MN**,, $**,,d..00T..00  HHO   h0& 1   h '        4<>>3CD3C<5%3CHD MM$NM3 u  .(3. "!!%//""56  !]E :Es=M2"M8#M8+M8.M=c v^^^^^^ "U4SjS5mT"S5mURSRnURH>nTRURSSS2URU- 5 URnM@ UR SS5m UUUUU 4SjmTR S:a T"TSS5 gg) Nc(>\rSrSrSrU4SjrSrg) display_hotpath..HotNodeic,XlSUl0Ulgr)rrErCrs rr,)display_hotpath..HotNode.__init__sIDJDMr!c>U=RU- slUSnURRU5nU(dT"U5nX@RU'[U5S:aSnU[U5:aJXR 5[ ;a0US- nU[U5:aXR 5[ ;aM0U[U5:aUR XSU5 ggU=RU- slgr9)rErCrhrAr|skipsadd)r*rrrrHrrHotNodes rrP$display_hotpath..HotNode.adds JJ$ J8DMM%%d+E &+ d#5zA~#e*n):):)<)EFA#e*n):):)<)Es5z>IIeBi." t# r!)rCrErN)rGrHrIrJr,rPrM)rQsrrQrKs   $ $r!rQrrshowtimeTc>URnURR5Vs/sH#oDRTTR-:dM!UPM% nnU(GaUS-S- nUR 5S-R S5nUR nSR U5U(aSOS-S-T(aS UR-OS-S -n U URTR- S -UU4-n [S S [U 5- 5n Sn U S :XaSn Sn U U SRU 5URb UROSSR [SS [[UR55- 55URS54-nX-n[URR5Vs/sHoDRPM sn5nURU- STR-:a SU-S-nOURU- S:XaSU-S-nTRUS-5 Un[U5S:dU(aUS- nURSSS9 UHnT"UU[U5S:5 M gs snfs snf)Nrrr*r!\|s %4.1f%%s %5.2fss %s %srr7s, s %s%s %d: %s%sslinerrg?sss TcUR$r?)rErs rr1display_hotpath.._write..&sr!r)rrCrrErxljustrTmaxrArjustrSstrrtsumrr&)nodedepthmultiple_siblingsrcvisiblechildrenindentrxrT listpattern liststring spacing_lenprefix codepattern codestring finalstringchildrensamplesnewdepthrH_writerqlimitrootrSs rrqdisplay_hotpath.._writespyy}}++- -!UTZZ=O1PA-   QY]F $.55b9H}}H &!-549/7: *CA  % TZZ'#-(J ac*o!56KFa*K$ k*#{{6 B #aST[[)9%:!:;<r" (J%1K!DMM4H4H4J"K4Jq774J"KLOzzO+sTZZ/?@)K7*D o-2)K7*D HH[5( )  ! #'8 MHT/@A$E 5(C$81$< =%g J#Ls I6I64I;F)r%rrPrrhrE) rrqrrrlasttimerrQrqrsrSs `` @@@@rrrs$$2 4=D||A##H,, dd#V[[8%;<;;zz*d+H6>6>p zzA~tQr!c Uc[RSS-n[RR U5(d&UR SU-5 UR S5 g0nUR H]nURVs/sHowRPM nnUR5 SRU5n X;a XYS-XY'MYSXY'M_ [R"5up[U S5n UR5HupU R SX4-5 M SSS5 UcS n[R"S X4-5 UR S U-5 gs snf!,(df  NG=f) NsHOMEs/flamegraph.plserror: missing %s s8get it here: https://github.com/brendangregg/FlameGraph ;rrs%s %d s~/flamegraph.svgsperl ~/flamegraph.pl %s > %ssWritten to %s )rrrrRexistsrr%rrTrrrmkstemprmrsystem)rrq scriptpath outputfilerrrrrrsfdrRrrEs rrr.s:%%g.1BB 77>>* % % '*45 MN E,,%+\\2\\2 yy =+/EKEK!HB dE d ;;=KD JJzTM1 2) ( II-0BBCHH * ,-%3  sE!6/E&& E4cHU[;a [U$[RR[R S5SnU/[ R-H<nU[R -nURU5(dM.U[U5Sn O U[U'U$)zAttempt to make the path to a Python module easier to read by removing whatever part of the Python search path it was found on.rrN) _pathcacher__file__rsplitrseprrR startswithrA)rRhgpathprks r simplifypathrPs  z$    % %bffa 0 3FX RVV ??6 " "F &D  ! Jt Kr!c /nURHn/nURHfnUR[R"UR 5UR =(d S[R"UR545 Mh URURU45 M [R"U5n[U[5(dURS5nURU5 g)Nrutf-8)r%rrrsysstrrRrSrTrjsondumpsrWrXencoder)rrqr%rrrs rrrasG,,\\E LLOOEJJ/LL&BOOENN3 "  U+, ::g D dE " "{{7#HHTNr!c ^^^ ^^^^^^^^/m[R"5m[R"5m0m/mUUU4SjmSm[5mURSRURSR- nUT-m[ UT-T5mUUUUUUU UU4 SjnURGHIm [ ST R55n[R"U5nTU:XaM>T(aOU(aHTSUS:Xa<TR5 UR5 T(aU(aTSUS:XaM<T(aU"5 T(aM[U5HynTRT R[T545 TRU5 UupT"[ T55n TR[SU U T RS-U SS 95 M{ [R"U5mGML T(aU"5 T(aM[T5V V s/sHupU T;dM U PM nn n [R"S [T555n[ R""[XS 9S S 9n[%U[&5(dUR)S5nUR+U5 UR+S5 gs sn n f)Nc>U(dgUT;aTU$T"USS5n[T5nUTU'TR[USSSUS-S95 UbTSRUS9 U$)Nrrz%s %s)categoryrr)rD)rArdictupdate)rrDmyidid2stackstack2idstackids rr write_to_chrome..stackids  H E? "qr#8}eAhqk%(8JKL   RL  v  . r!r1rrc D> T"[T 55nT R5upT R5up4T RU- nT Us=::aT ::a=O O:[UT-T R5nTR [ SUUUUS-SS95 gTR U5 g)NE.Ar)phrcatsftspid)tuplepopleftrr^rrrP)oldsidoldcatoldfuncoldtimeoldidxduration sampletime blacklistclamplastseen laststack maxthreshold minthresholdrr%rs rpoplast write_to_chrome..poplastsy)*#++-"**,;;( 8 3| 3Wu_fkk:J NN !C'   MM& !r!c3# UHfnS[[R"UR55UR=(d S4-[R"UR 54v Mh g7f)z%s:%drN)rrrrRrSrT).0rs r "write_to_chrome..s]  & !<=LL&B /  &sA.A0Br)rrrrrrc3@# UHup[U5U4v M g7fr?)r`)rrfrgs rrrs%"5Q "5s) traceEvents stackFramesr)rgrrZ) collectionsdequerr%rr^rrpopreversed appendleftrArrrn OrderedDictrrrWrXrr)rrqrr totaltimerrqstackrrRrsididxreventsframesrrrrrr%rrs `` ` @@@@@@@@rrrysiG!!#I  "H HH & EI R %% Q(<(<rs[0N`#    %% ** : *+,,^ 7:7:t##8 $__   (# !4* @2((+(+V!PH BAHHT nZz.> "0vr.A H zHHTVr!