Eu mudei minha configuração rsyslog (Debian 6.0) rsyslog para que ela use timestamps de alta precisão. Realmente, e isso me dá microssegundos, tanto nas entradas do kernel quanto em algumas outras entradas, como em fantoches. Mas as entradas do haproxy são precisas para o segundo. Por exemplo:
2012-04-23T17:48:23.122513+03:00 debianvboxtest puppet-agent[11367]: Finished catalog run in 1.82 seconds
2012-04-23T17:49:01+03:00 debianvboxtest haproxy[11555]: Proxy http-in started.
2012-04-23T17:49:27+03:00 debianvboxtest haproxy[11556]: 192.168.1.67:39202 [23/Apr/2012:17:49:26.273] http-in http-in/server01 0/0/0/0/1046 403 324 - - ---- 0/0/0/0/0 0/0 "GET / HTTP/1.1"
Este problema está no lado haproxy ou no lado do rsyslog?
A diferença entre o haproxy e outros processos era que o hparoxy estava logando para 127.0.0.1 (porta udp 514), enquanto os outros processos estavam logando para / dev / log. Eu mudei haproxy para logar em / dev / log também, e agora os timestamps também possuem microssegundos. Um bug ou um recurso do rsyslog.
O timestamp é escrito por rsyslog; faça um simples tcpdump de um desses pacotes se quiser ter certeza.