upstream/mercurial-mirror Files · tests/test-profile.t

hgweb: profile HTTP requests...

hgweb: profile HTTP requests Currently, running `hg serve --profile` doesn't yield anything useful: when the process is terminated the profiling output displays results from the main thread, which typically spends most of its time in select.select(). Furthermore, it has no meaningful results from mercurial.* modules because the threads serving HTTP requests don't actually get profiled. This patch teaches the hgweb wsgi applications to profile individual requests. If profiling is enabled, the profiler kicks in after HTTP/WSGI environment processing but before Mercurial's main request processing. The profile results are printed to the configured profiling output. If running `hg serve` from a shell, they will be printed to stderr, just before the HTTP request line is logged. If profiling to a file, we only write a single profile to the file because the file is not opened in append mode. We could add support for appending to files in a future patch if someone wants it. Per request profiling doesn't work with the statprof profiler because internally that profiler collects samples from the thread that *initially* requested profiling be enabled. I have plans to address this by vendoring Facebook's customized statprof and then improving it.

Gregory Szorc - - Load All Authors

File last commit:

r29787:80df0426 default


                r29787:80df0426

default

Download file

             test-profile.t
        
                    48 lines
            
             | 1.3 KiB
            
                | text/troff
            
             |
                Tads3Lexer
            
             / tests / test-profile.t
          
                    History
                
                 |
                  Source
                 | Raw
                 |Copy content
                 |Copy permalink

        Matt Mackall
    
tests: unify test-profile

              r12478
            
      test --time

        Martin Geisler
    
dispatch: lowercase --time message

              r16933
            
        $ hg --time help -q help 2>&1 | grep time > /dev/null

        Matt Mackall
    
tests: unify test-profile

              r12478
            
        $ hg init a

        $ cd a

        Mads Kiilerich
    
tests: change odd uses of 'if hghave' to #if

              r16898
            
      #if lsprof

        Matt Mackall
    
tests: unify test-profile

              r12478
            
      test --profile

        Mads Kiilerich
    
tests: change odd uses of 'if hghave' to #if

              r16898
            
        $ hg --profile st 2>../out

        $ grep CallCount ../out > /dev/null || cat ../out

        $ hg --profile --config profiling.output=../out st

        $ grep CallCount ../out > /dev/null || cat ../out

        Durham Goode
    
profiling: allow logging profile to the blackbox...

              r26191
            
        $ hg --profile --config profiling.output=blackbox --config extensions.blackbox= st

        $ grep CallCount .hg/blackbox.log > /dev/null || cat .hg/blackbox.log

        Mads Kiilerich
    
tests: change odd uses of 'if hghave' to #if

              r16898
            
        $ hg --profile --config profiling.format=text st 2>../out

        $ grep CallCount ../out > /dev/null || cat ../out

        $ echo "[profiling]" >> $HGRCPATH

        $ echo "format=kcachegrind" >> $HGRCPATH

        $ hg --profile st 2>../out

        $ grep 'events: Ticks' ../out > /dev/null || cat ../out

        $ hg --profile --config profiling.output=../out st

        $ grep 'events: Ticks' ../out > /dev/null || cat ../out

      #endif

        Mads Kiilerich
    
tests: add missing trailing 'cd ..'...

              r16913
            
        Gregory Szorc
    
hgweb: profile HTTP requests...

              r29787
            
      #if lsprof serve

      Profiling of HTTP requests works

        $ hg --profile --config profiling.format=text --config profiling.output=../profile.log serve -d -p $HGPORT --pid-file ../hg.pid -A ../access.log

        $ cat ../hg.pid >> $DAEMON_PIDS

        $ hg -q clone -U http://localhost:$HGPORT ../clone

      A single profile is logged because file logging doesn't append

        $ grep CallCount ../profile.log | wc -l

        \s*1 (re)

      #endif

        Mads Kiilerich
    
tests: add missing trailing 'cd ..'...

              r16913
            
        $ cd ..

	Site-wide shortcuts
/	Use quick search box
g h	Goto home page
g g	Goto my private gists page
g G	Goto my public gists page
g 0-9	Goto bookmarked items from 0-9
n r	New repository page
n g	New gist page

	Repositories
g s	Goto summary page
g c	Goto changelog page
g f	Goto files page
g F	Goto files page with file search activated
g p	Goto pull requests page
g o	Goto repository settings
g O	Goto repository access permissions settings
t s	Toggle sidebar on some pages

Matt Mackall tests: unify test-profile	r12478	test --time

Martin Geisler dispatch: lowercase --time message	r16933	$ hg --time help -q help 2>&1 \| grep time > /dev/null
Matt Mackall tests: unify test-profile	r12478	$ hg init a
		$ cd a

Mads Kiilerich tests: change odd uses of 'if hghave' to #if	r16898	#if lsprof

Matt Mackall tests: unify test-profile	r12478	test --profile

Mads Kiilerich tests: change odd uses of 'if hghave' to #if	r16898	$ hg --profile st 2>../out
		$ grep CallCount ../out > /dev/null \|\| cat ../out

		$ hg --profile --config profiling.output=../out st
		$ grep CallCount ../out > /dev/null \|\| cat ../out

Durham Goode profiling: allow logging profile to the blackbox...	r26191	$ hg --profile --config profiling.output=blackbox --config extensions.blackbox= st
		$ grep CallCount .hg/blackbox.log > /dev/null \|\| cat .hg/blackbox.log

Mads Kiilerich tests: change odd uses of 'if hghave' to #if	r16898	$ hg --profile --config profiling.format=text st 2>../out
		$ grep CallCount ../out > /dev/null \|\| cat ../out

		$ echo "[profiling]" >> $HGRCPATH
		$ echo "format=kcachegrind" >> $HGRCPATH

		$ hg --profile st 2>../out
		$ grep 'events: Ticks' ../out > /dev/null \|\| cat ../out

		$ hg --profile --config profiling.output=../out st
		$ grep 'events: Ticks' ../out > /dev/null \|\| cat ../out

		#endif
Mads Kiilerich tests: add missing trailing 'cd ..'...	r16913
Gregory Szorc hgweb: profile HTTP requests...	r29787	#if lsprof serve

		Profiling of HTTP requests works

		$ hg --profile --config profiling.format=text --config profiling.output=../profile.log serve -d -p $HGPORT --pid-file ../hg.pid -A ../access.log
		$ cat ../hg.pid >> $DAEMON_PIDS
		$ hg -q clone -U http://localhost:$HGPORT ../clone

		A single profile is logged because file logging doesn't append
		$ grep CallCount ../profile.log \| wc -l
		\s*1 (re)

		#endif

Mads Kiilerich tests: add missing trailing 'cd ..'...	r16913	$ cd ..