Como faço para medir os tempos de solicitação e resposta de uma só vez usando cURL?

659

Eu tenho um serviço web que recebe dados no formato JSON, processa os dados e retorna o resultado ao solicitante.

Quero medir a solicitação, resposta e tempo total usando cURL.

Meu exemplo de solicitação se parece com:

curl -X POST -d @file server:port

e atualmente medo isso usando o timecomando no Linux:

time curl -X POST -d @file server:port

O comando time mede apenas o tempo total - o que não é exatamente o que estou procurando.

Existe alguma maneira de medir os tempos de solicitação e resposta usando cURL?

sdasdadas
fonte

Respostas:

1677

A partir desta publicação brilhante no blog ... https://blog.josephscott.org/2011/10/14/timing-details-with-curl/

O cURL suporta saída formatada para os detalhes da solicitação (consulte a página de manual do cURL para detalhes , em -w, –write-out <format>). Para nossos propósitos, focaremos apenas nos detalhes de tempo fornecidos. Os tempos abaixo estão em segundos .

  1. Crie um novo arquivo, curl-format.txt, e cole em:

        time_namelookup:  %{time_namelookup}s\n
           time_connect:  %{time_connect}s\n
        time_appconnect:  %{time_appconnect}s\n
       time_pretransfer:  %{time_pretransfer}s\n
          time_redirect:  %{time_redirect}s\n
     time_starttransfer:  %{time_starttransfer}s\n
                        ----------\n
             time_total:  %{time_total}s\n
    
  2. Faça um pedido:

    curl -w "@curl-format.txt" -o /dev/null -s "http://wordpress.com/"
    

    Ou no Windows, é ...

    curl -w "@curl-format.txt" -o NUL -s "http://wordpress.com/"
    


O que isso faz:

-w "@curl-format.txt"diz ao cURL para usar nosso arquivo de formato
-o /dev/nullredireciona a saída da solicitação para / dev / null,
-s diz ao cURL para não mostrar que um medidor de progresso
"http://wordpress.com/"é a URL que estamos solicitando. Use aspas, principalmente se o seu URL tiver parâmetros de string de consulta "&"


E aqui está o que você recebe de volta:

   time_namelookup:  0.001s
      time_connect:  0.037s
   time_appconnect:  0.000s
  time_pretransfer:  0.037s
     time_redirect:  0.000s
time_starttransfer:  0.092s
                   ----------
        time_total:  0.164s


Criar um atalho para Linux / Mac (alias)

alias curltime="curl -w \"@$HOME/.curl-format.txt\" -o NUL -s "

Então você pode simplesmente ligar ...

curltime wordpress.org

Obrigado ao comentarista Pete Doyle!


Crie um script independente do Linux / Mac

Este script não requer um arquivo .txt separado para conter a formatação.

Crie um novo arquivo, curltime, em algum lugar do seu caminho executável e cole:

#!/bin/bash

curl -w @- -o /dev/null -s "$@" <<'EOF'
    time_namelookup:  %{time_namelookup}\n
       time_connect:  %{time_connect}\n
    time_appconnect:  %{time_appconnect}\n
   time_pretransfer:  %{time_pretransfer}\n
      time_redirect:  %{time_redirect}\n
 time_starttransfer:  %{time_starttransfer}\n
                    ----------\n
         time_total:  %{time_total}\n
EOF

Chame da mesma maneira que o alias:

curltime wordpress.org


Crie um atalho do Windows (também conhecido como arquivo BAT)

Coloque este comando em CURLTIME.BAT (na mesma pasta que curl.exe)

curl -w "@%~dp0curl-format.txt" -o NUL -s %*

Então você pode simplesmente ligar ...

curltime wordpress.org
Simon East
fonte
26
resposta incrível. obrigado. uma coisa que eu tinha que fazer era acrescentar \npara quebrar a linha no arquivo de texto
Jason Kim
2
No arquivo BAT janelas só envia o primeiro parâmetro, alteração a esta para passar todos os parâmetros e desativar echo o comando auto: @curl -w "@%~dp0curl-format.txt" -o NUL -s %*Grande resposta
padilo
Obrigado @udoh, atualizei a resposta para incluir isso.
Simon East
excelente resposta. como também incluo a data e hora atuais de quando a onda iniciou a solicitação?
precisa
4
Para Linux, fiz uma dotfile e um alias e parece funcionar bem: alias curltime="curl -w \"@$HOME/.curl-format.txt\" -o NUL -s ". Provavelmente também funciona no MacOS.
Pete Doyle
161

Aqui está a resposta:

curl -X POST -d @file server:port -w %{time_connect}:%{time_starttransfer}:%{time_total}

Todas as variáveis ​​usadas com -wpodem ser encontradas em man curl.

sdasdadas
fonte
19
É melhor para a experiência do usuário adicionar novas linhas:"\n%{time_connect}:%{time_starttransfer}:%{time_total}\n"
1
Para mim, não estava funcionando sem aspas. Eu sugeriria adicionar aspas ao especificar o formato / h / a / c / haproxy # ❯❯❯ curl -w "% {time_total} \ n" google.com -o / dev / null -s 0.055
Geek
@ Geek Geralmente, faz sentido mostrar erros ao operar no modo silencioso ( -sS).
X-yuri
138

Opção 1. Para medir total time:

curl -o /dev/null -s -w 'Total: %{time_total}s\n'  https://www.google.com

Saída de amostra:

insira a descrição da imagem aqui

Opção 2. Para chegar time to establish connection, TTFB: time to first bytee total time:

curl -o /dev/null -s -w 'Establish Connection: %{time_connect}s\nTTFB: %{time_starttransfer}s\nTotal: %{time_total}s\n'  https://www.google.com

Saída de amostra:

insira a descrição da imagem aqui

Ref: obtenha tempo de resposta com curl

Tho
fonte
53

Um atalho que você pode adicionar ao seu .bashrc etc, com base em outras respostas aqui:

function perf {
  curl -o /dev/null -s -w "%{time_connect} + %{time_starttransfer} = %{time_total}\n" "$1"
}

Uso:

> perf stackoverflow.com
0.521 + 0.686 = 1.290
mahemoff
fonte
5
Eu uso uma variação que exibe o número de bytes transferidos durante o tempo medido:curl -o /dev/null -s -w "time_total: %{time_total} sec\nsize_download: %{size_download} bytes\n" https://www.google.com
jambroseclarke
39

O seguinte é inspirado na resposta de Simon. É independente (não requer um arquivo de formato separado), o que o torna ótimo para inclusão no .bashrc.

curl_time() {
    curl -so /dev/null -w "\
   namelookup:  %{time_namelookup}s\n\
      connect:  %{time_connect}s\n\
   appconnect:  %{time_appconnect}s\n\
  pretransfer:  %{time_pretransfer}s\n\
     redirect:  %{time_redirect}s\n\
starttransfer:  %{time_starttransfer}s\n\
-------------------------\n\
        total:  %{time_total}s\n" "$@"
}

Além disso, deve funcionar com todos os argumentos que curlnormalmente levam, já que os "$@"justos os passam. Por exemplo, você pode fazer:

curl_time -X POST -H "Content-Type: application/json" -d '{"key": "val"}' https://postman-echo.com/post

Resultado:

   namelookup:  0,125000s
      connect:  0,250000s
   appconnect:  0,609000s
  pretransfer:  0,609000s
     redirect:  0,000000s
starttransfer:  0,719000s
-------------------------
        total:  0,719000s
Konstantin
fonte
34

Se você deseja analisar ou resumir a latência, tente o banco apache:

ab -n [number of samples] [url]

Por exemplo:

ab -n 100 http://www.google.com/

Ele mostrará:

This is ApacheBench, Version 2.3 <$Revision: 1757674 $>
Copyright 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
Licensed to The Apache Software Foundation, http://www.apache.org/

Benchmarking www.google.com (be patient).....done


Server Software:        gws
Server Hostname:        www.google.com
Server Port:            80

Document Path:          /
Document Length:        12419 bytes

Concurrency Level:      1
Time taken for tests:   10.700 seconds
Complete requests:      100
Failed requests:        97
   (Connect: 0, Receive: 0, Length: 97, Exceptions: 0)
Total transferred:      1331107 bytes
HTML transferred:       1268293 bytes
Requests per second:    9.35 [#/sec] (mean)
Time per request:       107.004 [ms] (mean)
Time per request:       107.004 [ms] (mean, across all concurrent requests)
Transfer rate:          121.48 [Kbytes/sec] received

Connection Times (ms)
              min  mean[+/-sd] median   max
Connect:       20   22   0.8     22      26
Processing:    59   85 108.7     68     911
Waiting:       59   85 108.7     67     910
Total:         80  107 108.8     90     932

Percentage of the requests served within a certain time (ms)
  50%     90
  66%     91
  75%     93
  80%     95
  90%    105
  95%    111
  98%    773
  99%    932
 100%    932 (longest request)
Andong Zhan
fonte
1
muito mais simples que as outras respostas. Esqueci completamente esse comando!
FacePalm 28/03/19
Esta é uma resposta fantástica. E abaceita com facilidade muitas das mesmas bandeiras que, curlpor exemplo, -Hpara cabeçalhos. Eu usei esse comando para comparar os tempos de resposta de uma API de terceiros (fornecendo o token do portador em um cabeçalho de autorização). Trabalhou brilhantemente.
tsamb 27/01
21

Outra maneira é configurar ~/.curlrcassim

-w "\n\n==== cURL measurements stats ====\ntotal: %{time_total} seconds \nsize: %{size_download} bytes \ndnslookup: %{time_namelookup} seconds \nconnect: %{time_connect} seconds \nappconnect: %{time_appconnect} seconds \nredirect: %{time_redirect} seconds \npretransfer: %{time_pretransfer} seconds \nstarttransfer: %{time_starttransfer} seconds \ndownloadspeed: %{speed_download} byte/sec \nuploadspeed: %{speed_upload} byte/sec \n\n"

Então a saída de curlé

❯❯ curl -I https://google.com
HTTP/2 301
location: https://www.google.com/
content-type: text/html; charset=UTF-8
date: Mon, 04 Mar 2019 08:02:43 GMT
expires: Wed, 03 Apr 2019 08:02:43 GMT
cache-control: public, max-age=2592000
server: gws
content-length: 220
x-xss-protection: 1; mode=block
x-frame-options: SAMEORIGIN
alt-svc: quic=":443"; ma=2592000; v="44,43,39"



==== cURL measurements stats ====
total: 0.211117 seconds
size: 0 bytes
dnslookup: 0.067179 seconds
connect: 0.098817 seconds
appconnect: 0.176232 seconds
redirect: 0.000000 seconds
pretransfer: 0.176438 seconds
starttransfer: 0.209634 seconds
downloadspeed: 0.000 byte/sec
uploadspeed: 0.000 byte/sec
Hieu Huynh
fonte
Você me mostraria a referência a documentos mais detalhados sobre isso?
Trần Đức Tâm
Detalhes do @ TrầnĐứcTâm no livro oficial do curl ec.haxx.se/usingcurl-writeout.html
Huynh
10

Ei, é melhor que o Apache Bench, tem menos problemas com SSL

./hey https://google.com -more
Summary:
  Total:    3.0960 secs
  Slowest:  1.6052 secs
  Fastest:  0.4063 secs
  Average:  0.6773 secs
  Requests/sec: 64.5992

Response time histogram:
  0.406 [1] |
  0.526 [142]   |∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎∎
  0.646 [1] |
  0.766 [6] |∎∎
  0.886 [0] |
  1.006 [0] |
  1.126 [0] |
  1.246 [12]    |∎∎∎
  1.365 [32]    |∎∎∎∎∎∎∎∎∎
  1.485 [5] |∎
  1.605 [1] |

Latency distribution:
  10% in 0.4265 secs
  25% in 0.4505 secs
  50% in 0.4838 secs
  75% in 1.2181 secs
  90% in 1.2869 secs
  95% in 1.3384 secs
  99% in 1.4085 secs

Details (average, fastest, slowest):
  DNS+dialup:    0.1150 secs, 0.0000 secs, 0.4849 secs
  DNS-lookup:    0.0032 secs, 0.0000 secs, 0.0319 secs
  req write:     0.0001 secs, 0.0000 secs, 0.0007 secs
  resp wait:     0.2068 secs, 0.1690 secs, 0.4906 secs
  resp read:     0.0117 secs, 0.0011 secs, 0.2375 secs

Status code distribution:
  [200] 200 responses

Referências

Jonathan
fonte
9

Outra opção que talvez seja a mais simples em termos de linha de comando é adicionar a --trace-timeopção interna:

curl -X POST -d @file server:port --trace-time

Mesmo que tecnicamente não produza os tempos das várias etapas, conforme solicitado pelo OP, ele exibe os registros de data e hora de todas as etapas da solicitação, como mostrado abaixo. Com isso, você pode (com bastante facilidade) calcular quanto tempo cada etapa levou.

$ curl https://www.google.com --trace-time -v -o /dev/null
13:29:11.148734 * Rebuilt URL to: https://www.google.com/
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     013:29:11.149958 *   Trying 172.217.20.36...
13:29:11.149993 * TCP_NODELAY set
13:29:11.163177 * Connected to www.google.com (172.217.20.36) port 443 (#0)
13:29:11.164768 * ALPN, offering h2
13:29:11.164804 * ALPN, offering http/1.1
13:29:11.164833 * successfully set certificate verify locations:
13:29:11.164863 *   CAfile: none
  CApath: /etc/ssl/certs
13:29:11.165046 } [5 bytes data]
13:29:11.165099 * (304) (OUT), TLS handshake, Client hello (1):
13:29:11.165128 } [512 bytes data]
13:29:11.189518 * (304) (IN), TLS handshake, Server hello (2):
13:29:11.189537 { [100 bytes data]
13:29:11.189628 * TLSv1.2 (IN), TLS handshake, Certificate (11):
13:29:11.189658 { [2104 bytes data]
13:29:11.190243 * TLSv1.2 (IN), TLS handshake, Server key exchange (12):
13:29:11.190277 { [115 bytes data]
13:29:11.190507 * TLSv1.2 (IN), TLS handshake, Server finished (14):
13:29:11.190539 { [4 bytes data]
13:29:11.190770 * TLSv1.2 (OUT), TLS handshake, Client key exchange (16):
13:29:11.190797 } [37 bytes data]
13:29:11.190890 * TLSv1.2 (OUT), TLS change cipher, Client hello (1):
13:29:11.190915 } [1 bytes data]
13:29:11.191023 * TLSv1.2 (OUT), TLS handshake, Finished (20):
13:29:11.191053 } [16 bytes data]
13:29:11.204324 * TLSv1.2 (IN), TLS handshake, Finished (20):
13:29:11.204358 { [16 bytes data]
13:29:11.204417 * SSL connection using TLSv1.2 / ECDHE-ECDSA-CHACHA20-POLY1305
13:29:11.204451 * ALPN, server accepted to use h2
13:29:11.204483 * Server certificate:
13:29:11.204520 *  subject: C=US; ST=California; L=Mountain View; O=Google LLC; CN=www.google.com
13:29:11.204555 *  start date: Oct  2 07:29:00 2018 GMT
13:29:11.204585 *  expire date: Dec 25 07:29:00 2018 GMT
13:29:11.204623 *  subjectAltName: host "www.google.com" matched cert's "www.google.com"
13:29:11.204663 *  issuer: C=US; O=Google Trust Services; CN=Google Internet Authority G3
13:29:11.204701 *  SSL certificate verify ok.
13:29:11.204754 * Using HTTP2, server supports multi-use
13:29:11.204795 * Connection state changed (HTTP/2 confirmed)
13:29:11.204840 * Copying HTTP/2 data in stream buffer to connection buffer after upgrade: len=0
13:29:11.204881 } [5 bytes data]
13:29:11.204983 * Using Stream ID: 1 (easy handle 0x55846ef24520)
13:29:11.205034 } [5 bytes data]
13:29:11.205104 > GET / HTTP/2
13:29:11.205104 > Host: www.google.com
13:29:11.205104 > User-Agent: curl/7.61.0
13:29:11.205104 > Accept: */*
13:29:11.205104 > 
13:29:11.218116 { [5 bytes data]
13:29:11.218173 * Connection state changed (MAX_CONCURRENT_STREAMS == 100)!
13:29:11.218211 } [5 bytes data]
13:29:11.251936 < HTTP/2 200 
13:29:11.251962 < date: Fri, 19 Oct 2018 10:29:11 GMT
13:29:11.251998 < expires: -1
13:29:11.252046 < cache-control: private, max-age=0
13:29:11.252085 < content-type: text/html; charset=ISO-8859-1
13:29:11.252119 < p3p: CP="This is not a P3P policy! See g.co/p3phelp for more info."
13:29:11.252160 < server: gws
13:29:11.252198 < x-xss-protection: 1; mode=block
13:29:11.252228 < x-frame-options: SAMEORIGIN
13:29:11.252262 < set-cookie: 1P_JAR=2018-10-19-10; expires=Sun, 18-Nov-2018 10:29:11 GMT; path=/; domain=.google.com
13:29:11.252297 < set-cookie: NID=141=pzXxp1jrJmLwFVl9bLMPFdGCtG8ySQKxB2rlDWgerrKJeXxfdmB1HhJ1UXzX-OaFQcnR1A9LKYxi__PWMigjMBQHmI3xkU53LI_TsYRbkMNJNdxs-caQQ7fEcDGE694S; expires=Sat, 20-Apr-2019 10:29:11 GMT; path=/; domain=.google.com; HttpOnly
13:29:11.252336 < alt-svc: quic=":443"; ma=2592000; v="44,43,39,35"
13:29:11.252368 < accept-ranges: none
13:29:11.252408 < vary: Accept-Encoding
13:29:11.252438 < 
13:29:11.252473 { [5 bytes data]
100 12215    0 12215    0     0   112k      0 --:--:-- --:--:-- --:--:--  112k
13:29:11.255674 * Connection #0 to host www.google.com left intact
Per Lundberg
fonte
Essa é realmente uma ótima resposta que provavelmente se encaixará na maioria dos casos de uso que as pessoas estão procurando. As outras respostas são ótimas para soluções completas e detalhadas, mas isso é bom para verificar rapidamente os tempos de ida e volta.
22418 Chris Vandevelde
Obrigado @ChrisVandevelde. Sim, eu estava ciente de que havia "algo" assim (havia usado esse parâmetro antes), então procurei no Google esse post do SO e encontrei a forma mais sofisticada, mas ... tive a sensação de que havia outra maneira também . :) Como você diz, é bem simples em sua simplicidade e às vezes é suficiente para casos de uso mais simples.
Per Lundberg
8

curl -v --trace-time Isso deve ser feito no modo detalhado

PAUL SSEMAKULA
fonte
4

Aqui está a string com a qual você pode usar -w, contém todas as opções curl -wcompatíveis.

{"contentType":"%{content_type}","filenameEffective":"%{filename_effective}","ftpEntryPath":"%{ftp_entry_path}","httpCode":"%{http_code}","httpConnect":"%{http_connect}","httpVersion":"%{http_version}","localIp":"%{local_ip}","localPort":"%{local_port}","numConnects":"%{num_connects}","numRedirects":"%{num_redirects}","proxySslVerifyResult":"%{proxy_ssl_verify_result}","redirectUrl":"%{redirect_url}","remoteIp":"%{remote_ip}","remotePort":"%{remote_port}","scheme":"%{scheme}","size":{"download":"%{size_download}","header":"%{size_header}","request":"%{size_request}","upload":"%{size_upload}"},"speed":{"download":"%{speed_download}","upload":"%{speed_upload}"},"sslVerifyResult":"%{ssl_verify_result}","time":{"appconnect":"%{time_appconnect}","connect":"%{time_connect}","namelookup":"%{time_namelookup}","pretransfer":"%{time_pretransfer}","redirect":"%{time_redirect}","starttransfer":"%{time_starttransfer}","total":"%{time_total}"},"urlEffective":"%{url_effective}"}

gera JSON.

ATilara
fonte
Prepending \najuda a separar a temporização, quando o corpo não termina com a nova linha:curl -w '\n{"contentType":"..."}...
beni Cherniavsky-Paskin
2

Aqui está uma linha única do Bash para atingir o mesmo servidor repetidamente:

for i in {1..1000}; do curl -s -o /dev/null -w "%{time_total}\n" http://server/get_things; done
Lucas
fonte
0

Esta é uma versão modificada da resposta de Simons, que torna a saída multi-alinhada uma única linha. Ele também apresenta o registro de data e hora atual, para que seja mais fácil seguir cada linha de saída.

Exemplo de formato de arquivo
$ cat time-format.txt
time_namelookup:%{time_namelookup} time_connect:%{time_connect} time_appconnect:%{time_appconnect} time_pretransfer:%{time_pretransfer} time_redirect:%{time_redirect} time_starttransfer:%{time_starttransfer} time_total:%{time_total}\n
exemplo cmd
$ while [ 1 ];do echo -n "$(date) - " ; curl -w @curl-format.txt -o /dev/null -s https://myapp.mydom.com/v1/endpt-http; sleep 1; done | grep -v time_total:0
resultados
Mon Dec 16 17:51:47 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:0.172 time_pretransfer:0.172 time_redirect:0.000 time_starttransfer:1.666 time_total:1.666
Mon Dec 16 17:51:50 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:0.175 time_pretransfer:0.175 time_redirect:0.000 time_starttransfer:3.794 time_total:3.795
Mon Dec 16 17:51:55 UTC 2019 - time_namelookup:0.004 time_connect:0.017 time_appconnect:0.175 time_pretransfer:0.175 time_redirect:0.000 time_starttransfer:1.971 time_total:1.971
Mon Dec 16 17:51:58 UTC 2019 - time_namelookup:0.004 time_connect:0.014 time_appconnect:0.173 time_pretransfer:0.173 time_redirect:0.000 time_starttransfer:1.161 time_total:1.161
Mon Dec 16 17:52:00 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:0.166 time_pretransfer:0.167 time_redirect:0.000 time_starttransfer:1.434 time_total:1.434
Mon Dec 16 17:52:02 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:0.177 time_pretransfer:0.177 time_redirect:0.000 time_starttransfer:5.119 time_total:5.119
Mon Dec 16 17:52:08 UTC 2019 - time_namelookup:0.004 time_connect:0.014 time_appconnect:0.172 time_pretransfer:0.172 time_redirect:0.000 time_starttransfer:30.185 time_total:30.185
Mon Dec 16 17:52:39 UTC 2019 - time_namelookup:0.004 time_connect:0.014 time_appconnect:0.164 time_pretransfer:0.164 time_redirect:0.000 time_starttransfer:30.175 time_total:30.176
Mon Dec 16 17:54:28 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:3.191 time_pretransfer:3.191 time_redirect:0.000 time_starttransfer:3.212 time_total:3.212
Mon Dec 16 17:56:08 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:1.184 time_pretransfer:1.184 time_redirect:0.000 time_starttransfer:1.215 time_total:1.215
Mon Dec 16 18:00:24 UTC 2019 - time_namelookup:0.004 time_connect:0.015 time_appconnect:0.181 time_pretransfer:0.181 time_redirect:0.000 time_starttransfer:1.267 time_total:1.267

Eu usei o acima para capturar respostas lentas no endpoint acima.

slm
fonte