Como ajustar o comando time para medir o tempo decorrido do programa

4

Estou tentando obter o tempo que meu programa leva para terminar (também conhecido como tempo decorrido), então estou usando o time comum.

O que eu ganho fazendo isso é 3 medições: usuário, sistema e total. Isso é bom, mas descobri que a hora do usuário em que estou interessado tem apenas duas casas decimais e eu precisaria de mais. Existe alguma maneira de obter mais casas decimais do meu comando de tempo?

Exemplo: time ./myCProgram
Saída: 0,17s usuário 1,21s sistema 130% cpu 0,187 total

Desejo desejado: 0.17000s usuário sistema 1.21s 130% cpu 0.187 no total (ou mais casas decimais)

    
por Ini 09.11.2012 / 20:19

2 respostas

5

Se tudo que você quer é tempo decorrido, então com zsh ou ksh93:

$ typeset -F SECONDS=0; sleep 1; print "$SECONDS"
1.0012850761

Agora, se esse tipo de precisão faz sentido é outra questão.

    
por 09.11.2012 / 21:20
5

Calculando tempo em nanossegundos em GNU/Linux , usando bash .

AVISO Este post foi tornado obsoleto por um novo método interessante discutido aqui: Profiling bash (3 respostas) e com completo pronto para uso arquivo fonte lá: Elap-bash V3

Formas de recuperar valores confiáveis

Há alguma maneira de pedir tempo em uma granularidade mais fina que um segundo.

Primeiro, mas não melhor: 1/100 de segundo

Por 1/100 de segundo, você pode simplesmente consultar /proc/uptime :

sed 's/ .*$//' /proc/uptime 
276440.58

O desvio de tempo para adicionar para o cálculo do tempo UTC real pode ser obtido por um dos seguintes:

ps ho lstart 1 | date -f - +%s
1357193821

ou

date +%s.%N-$(sed 's/ .*$//' /proc/uptime ) | bc -l
1357193821.088187101

Como o offset é estático, esse valor pode ser calculado apenas 1 vez, no início do script.

1/1000000000 de segundos, usando somente proc entradas:

Para computação com nanossegundos , há um proc entry , denominado /proc/timer_list , que contém uptime e offset :

sed < /proc/timer_list -ne '/now at/p;/offset/{p;q}'
now at 276350507668586 nsecs
  .offset:     1357193821075753443 nsecs

Então isso

echo $(($(sed < /proc/timer_list -ne '
    /^now at/{s/now at \([0-9]*\) ns.*$//;H};
    /offset/{s/^.*: *\([0-9]*\) ns.*$//;G;s/\n\+/+/;p;q}'))) 
1357470173543424035

é o número inteiro de nanossegundos decorridos em 1970-1-1 00:00:00 UTC .

Para números inteiros em , não é necessário usar bc e para analisar arquivos proc, podemos usar mapfile , que é um bash builtin :

# first, some static variables (with fork, but only 1 time, at begin;):
nowAtLine=$(($(sed -ne < /proc/timer_list '/now at/{=;q}')-1))
offset=$(sed -ne < /proc/timer_list '
    /offset/{s/^.*: *\([0-9]*\) n.*$//p;q}')

# than this will be a lot quicker than a fork:
mapfile -n 1 -s $nowAtLine timerList </proc/timer_list &&
    timerList=($timerList) &&
    echo $((${timerList[2]}+offset))
1357470173543424035

1 / 1000000000th de segundos, usando a ferramenta date binary:

E, se não existirem, poderíamos ligar para date , lembrando que é um fork que demora muito mais tempo do que ler proc files , permanecendo em apenas uma sessão bash.

date +%s%N
1357470074808968375

Minha função elap.bash

com base nisso, eu escrevi uma função elap bash , com dois contadores: 1 para cada chamada e outro para uma espécie de overall duration .

Uso:

Primeiro, esta é uma função , não um script . Você tem que source deles em sua sessão bash atual para usá-lo.

. elap.bash
Sintaxe:
elap [ [ -r | -R ] | [ -n ] [ -t | -T ] [<simple text report>] ]
    -r reset first counter only, don't output anything, like -R...
    -R reset both counters, (both -r and -R must by unique argument)
    -n don't reset any counter (just print)
    -t print both counters (reset first counter)
    -T print and reset

Para redefinir os dois contadores antes de usá-lo:

elap -R
find /usr/bin >/dev/null
elap browsing /usr/bin

     0.025389543 browsing /usr/bin

Você pode adicionar alguns marcadores em um script:

#!/bin/bash
. elap.bash
  elap -R
tar -cf /tmp/test.tar -C / bin
  elap making a tarball of $(stat -c %s /tmp/test.tar) bytes
gzip /tmp/test.tar
  elap compressing them to $(stat -c %s /tmp/test.tar.gz) bytes
scp /tmp/test.tar.gz [email protected]:backups/
  elap sending file to backup server
rm /tmp/test.tar.gz
  elap removing compressed tarball
elap -t total duration

     0.043223957 making a tarball of 5877760 bytes
     0.667249628 compressing them to 2742537 bytes
test.tar.gz                                  100% 2678KB 2.6MB/s 00:00
     0.380779818 sending file to backup server
     0.010262259 removing compressed tarball
     0.003566335      1.105081997 total duration

Usando trap debug para seguir passo a passo

Com ou sem a opção -t , usando trap debug para o passo a passo, com menos alteração de script (adicionadas quatro linhas na parte superior do script e uma na parte inferior):

Como o trapping para debug ocorrerá antes de $BASH_COMMAND execution, precisamos armazenar conteúdo variável em uma variável $BASH_LAST para a impressão correta e adicionar um > dummy comando na parte inferior do script.

#!/bin/bash

. elap.bash
elap -R
export BASH_LAST=Starting
trap 'elap -t $BASH_LAST;BASH_LAST=$BASH_COMMAND' debug 

tar -cf /tmp/test.tar -C / bin
gzip /tmp/test.tar
scp /tmp/test.tar.gz [email protected]:backups/
rm /tmp/test.tar.gz

exit $?

O comando exit $? é necessário para despejar statistiques após o último comando rm .

     0.001011086      0.001011086 Starting
     0.045175969      0.046187055 tar -cf /tmp/test.tar -C / bin
     0.651394209      0.697581264 gzip /tmp/test.tar
test.tar.gz                                  100% 2678KB 2.6MB/s 00:00
     0.374499354      1.072080618 scp /tmp/test.tar.gz [email protected]:backups/
     0.007160101      1.079240719 rm /tmp/test.tar.gz

A fonte da função

Onde você pode cortar (ou reordenar) uptime part, se preferir usar garfo para date +%s%N em vez de usar granularidade de 1/100 de segundo.

#
# Bash source file for fine elapsed time reporting
# based on /proc/timer_list, display elapsed time in nanosecs
# or /proc/uptime if timer_list not present, for 100th of secs.
# if none of them if present, fall back to *fork* 'date +%s%N'.
#
# (C) 2011-2012 Felix Hauri - [email protected]
# Licensed under terms of LGPL v3. www.gnu.org

# Usage:
#   load script into bash using ''source elap.bash''
#
# Syntaxe: elap [ [ -r | -R ] | [ -n ] [ -t | -T ] [<simple text report>] ]
#   -r reset first counter only, don't output anything, like -R...
#   -R reset both counters, (both -r and -R must by unique argument)
#   -n don't reset any counter (just print)
#   -t print both counters (reset first counter)
#   -T print and reset
#
# nota: using ''-n'' in combinaison with any of ''-r'' or ''-R'' is buggy.

export _elaP_now _elaP_last _elaP_elap _elaP_last2 _elaP_dec

if [ -r /proc/timer_list ] ;then
    _elaP_file=/proc/timer_list
    _elaP_dec=9
    _elaP_field=2
    mapfile < $_elaP_file _elaP_now
    _elaP_line=0
    while [ "${_elaP_now[_elaP_line]}" == \
    "${_elaP_now[_elaP_line]#*now at}" ] ;do
    ((_elaP_line++))
    done
    eval 'function elap_getNow() {
     mapfile -n 1 -s '$_elaP_line' <'$_elaP_file' _elaP_now
         _elaP_now=($_elaP_now)
         _elaP_now=${_elaP_now[_elaP_field]}
         }'
else
    #      --- To be removed for nanoseconds only
    if [ -r /proc/uptime ] ;then
    _elaP_dec=2
    function elap_getNow() {
        read -a _elaP_now </proc/uptime _elaP_now
            _elaP_now=${_elaP_now//./}
    }
    else # --- End of part to be removed for ns only.
    _elaP_dec=9
    function elap_getNow() { _elaP_now=$(date +%s%N) ;}
    fi   # --- Remove this line too for ns only.
fi

export -f elap_getNow

elap() {
    local _Z9=000000000
    local _elaP_setLast=true
    [ "$1" == "-n" ] && shift && _elaP_setLast=false
    [ "$2" == "-n" ] && set -- $1 ${@:3} && _elaP_setLast=false

    elap_getNow

    _elaP_elap=$((_elaP_now - _elaP_last))
    [ ${#_elaP_elap} -lt $_elaP_dec ] && \
    _elaP_elap=${_Z9:0:_elaP_dec-${#_elaP_elap}}$_elaP_elap
    [ "${*}" == "-R" ] &&  _elaP_last2=$_elaP_now || \
    [ "${*}" == "-r" ] || if [ "$1" == "-t" ] || [ "$1" == "-T" ] ;then
    local _elaP_setLast2=false
    [ "$1" == "-T" ] && _elaP_setLast2=true
    shift
            _elaP_elap2=$((_elaP_now - _elaP_last2))
    [ ${#_elaP_elap2} -lt $_elaP_dec ] && \
        _elaP_elap2="${_Z9:0:_elaP_dec-${#_elaP_elap2}}$_elaP_elap2"
        printf "%6d.%s %6d.%s %s\n" \
        "${_elaP_elap:0:${#_elaP_elap}-$_elaP_dec}" \
        "${_elaP_elap:${#_elaP_elap}-_elaP_dec}" \
        "${_elaP_elap2:0:${#_elaP_elap2}-$_elaP_dec}" \
        "${_elaP_elap2:${#_elaP_elap2}-_elaP_dec}"      "${*}"
    $_elaP_setLast2 && _elaP_last2=$_elaP_now
    else
        printf "%6d.%s %s\n" \
        "${_elaP_elap:0:${#_elaP_elap}-$_elaP_dec}" \
        "${_elaP_elap:${#_elaP_elap}-_elaP_dec}"        "${*}"
    fi
    $_elaP_setLast && _elaP_last=$_elaP_now
}

export -f elap
    
por 06.01.2013 / 13:10