[syslog-ng]syslog-ng freezes under FreeBSD

Mon, 29 Nov 2004 10:38:59 +0100 (MET)

> On Mon, 2004-11-22 at 12:42, Jost Menke wrote:
> > Hi all,
> > 
> > I have installed syslog-ng-1.6.5 on a FreeBSD 5.3 release system with 3
> > remote machines logging to it. Logging works fine for several hours, but
> > after some time it completely freezes without a clear reason. The
> process
> > can only be stopped with kill -9. Any ideas?
> 
> can you check the process with strace/ktrace/truss what it is doing?
> maybe it blocks on a DNS query, or something similar?
> 
> -- 
> Bazsi
> 

OK, it took some time because I had a few days off but here are the last
relevant lines of the ktrace output. I removed the actual log entries for
privacy reasons. Seems to me like a device-related problem, but take a look
at it yourself:

 27619 syslog-ng 376052.570456 RET   recvfrom 290/0x122
 27619 syslog-ng 376052.570544 CALL  gettimeofday(0xbfbfe758,0)
 27619 syslog-ng 376052.570570 RET   gettimeofday 0
 27619 syslog-ng 376052.570648 CALL  gettimeofday(0xbfbfe8f8,0)
 27619 syslog-ng 376052.570674 RET   gettimeofday 0
 27619 syslog-ng 376052.570749 CALL  gettimeofday(0xbfbfe7e8,0)
 27619 syslog-ng 376052.570776 RET   gettimeofday 0
 27619 syslog-ng 376052.570891 CALL  gettimeofday(0xbfbfeb48,0)
 27619 syslog-ng 376052.570918 RET   gettimeofday 0
 27619 syslog-ng 376052.570992 CALL  poll(0xbfbfeb20,0x8,0x64)
 27619 syslog-ng 376052.571012 RET   poll 2
 27619 syslog-ng 376052.571086 CALL  write(0x4,0xbfbfda70,0x13c)
 27619 syslog-ng 376052.571120 GIO   fd 4 wrote 316 bytes
       "-> log entry removed <-"
 27619 syslog-ng 376052.571133 RET   write 316/0x13c
 27619 syslog-ng 376052.571218 CALL 
recvfrom(0x5,0x807b800,0x800,0,0xbfbfe9a0,0xbfbfe99c)
 27619 syslog-ng 376052.571242 GIO   fd 5 read 210 bytes
       "-> log entry removed <-"
 27619 syslog-ng 376052.571272 RET   recvfrom 210/0xd2
 27619 syslog-ng 376052.571361 CALL  gettimeofday(0xbfbfe758,0)
 27619 syslog-ng 376052.571387 RET   gettimeofday 0
 27619 syslog-ng 376052.571464 CALL  gettimeofday(0xbfbfe8f8,0)
 27619 syslog-ng 376052.571490 RET   gettimeofday 0
 27619 syslog-ng 376052.571566 CALL  gettimeofday(0xbfbfe7e8,0)
 27619 syslog-ng 376052.571593 RET   gettimeofday 0
 27619 syslog-ng 376052.571690 CALL  getpid
 27619 syslog-ng 376052.571705 RET   getpid 27619/0x6be3
 27619 syslog-ng 376052.571780 CALL  gettimeofday(0xbfbfe608,0)
 27619 syslog-ng 376052.571806 RET   gettimeofday 0
 27619 syslog-ng 376052.571914 CALL  open(0x8078fc8,0x820d,0x180)
 27619 syslog-ng 376052.571940 NAMI  "/dev/console"
 27619 syslog-ng 376052.572014 NAMI  "/dev/consolectl"
 27619 syslog-ng 376052.572121 RET   open 10/0xa
 27619 syslog-ng 376052.572226 CALL  chown(0x8078fc8,0,0)
 27619 syslog-ng 376052.572242 NAMI  "/dev/console"
 27619 syslog-ng 376052.572268 RET   chown 0
 27619 syslog-ng 376052.572351 CALL  chmod(0x8078fc8,0x180)
 27619 syslog-ng 376052.572367 NAMI  "/dev/console"
 27619 syslog-ng 376052.572391 RET   chmod 0
 27619 syslog-ng 376052.572480 CALL  fcntl(0xa,0x3,0x50)
 27619 syslog-ng 376052.572496 RET   fcntl 13/0xd
 27619 syslog-ng 376052.572566 CALL  fcntl(0xa,0x4,0xd)
 27619 syslog-ng 376052.572595 RET   fcntl 0
 27619 syslog-ng 376052.572665 CALL  fcntl(0xa,0x2,0x1)
 27619 syslog-ng 376052.572680 RET   fcntl 0
 27619 syslog-ng 376052.572823 CALL  open(0x805a0ca,0x8000,0xbfbfe898)
 27619 syslog-ng 376052.572842 NAMI  "/var/run/utmp"
 27619 syslog-ng 376052.572885 RET   open 12/0xc
 27619 syslog-ng 376052.572971 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.580958 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.580973 RET   read 44/0x2c
 27619 syslog-ng 376052.581001 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581018 NAMI  "/dev/"
 27619 syslog-ng 376052.581045 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581061 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581081 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581094 RET   read 44/0x2c
 27619 syslog-ng 376052.581108 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581123 NAMI  "/dev/"
 27619 syslog-ng 376052.581144 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581158 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581177 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581190 RET   read 44/0x2c
 27619 syslog-ng 376052.581204 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581219 NAMI  "/dev/"
 27619 syslog-ng 376052.581239 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581253 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581272 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581285 RET   read 44/0x2c
 27619 syslog-ng 376052.581298 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581314 NAMI  "/dev/"
 27619 syslog-ng 376052.581334 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581348 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581366 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581380 RET   read 44/0x2c
 27619 syslog-ng 376052.581394 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581409 NAMI  "/dev/"
 27619 syslog-ng 376052.581429 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581443 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581461 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581475 RET   read 44/0x2c
 27619 syslog-ng 376052.581488 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581504 NAMI  "/dev/"
 27619 syslog-ng 376052.581523 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581538 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581556 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581569 RET   read 44/0x2c
 27619 syslog-ng 376052.581583 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581598 NAMI  "/dev/"
 27619 syslog-ng 376052.581618 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581632 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581651 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581664 RET   read 44/0x2c
 27619 syslog-ng 376052.581678 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581693 NAMI  "/dev/"
 27619 syslog-ng 376052.581713 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581727 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581746 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581759 RET   read 44/0x2c
 27619 syslog-ng 376052.581772 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581788 NAMI  "/dev/"
 27619 syslog-ng 376052.581808 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581822 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581841 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581854 RET   read 44/0x2c
 27619 syslog-ng 376052.581868 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581884 NAMI  "/dev/"
 27619 syslog-ng 376052.581904 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.581918 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.581936 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.581950 RET   read 44/0x2c
 27619 syslog-ng 376052.581963 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.581979 NAMI  "/dev/"
 27619 syslog-ng 376052.581999 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.582013 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.582031 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.582044 RET   read 44/0x2c
 27619 syslog-ng 376052.582058 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.582074 NAMI  "/dev/"
 27619 syslog-ng 376052.582094 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.582108 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.582126 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.582139 RET   read 44/0x2c
 27619 syslog-ng 376052.582153 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.582169 NAMI  "/dev/"
 27619 syslog-ng 376052.582189 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.582203 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.582221 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.582234 RET   read 44/0x2c
 27619 syslog-ng 376052.582248 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.582264 NAMI  "/dev/"
 27619 syslog-ng 376052.582283 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.582298 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.582316 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.582329 RET   read 44/0x2c
 27619 syslog-ng 376052.582343 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.582358 NAMI  "/dev/"
 27619 syslog-ng 376052.582378 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.582393 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.582411 GIO   fd 12 read 44 bytes

"\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"
 27619 syslog-ng 376052.582424 RET   read 44/0x2c
 27619 syslog-ng 376052.582438 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.582453 NAMI  "/dev/"
 27619 syslog-ng 376052.582473 RET   open -1 errno 21 Is a directory
 27619 syslog-ng 376052.582488 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 376052.582506 GIO   fd 12 read 44 bytes
       0x0000 7474 7970 3000 0000 0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000 af05 a641 
|ttyp0......................................A|
 27619 syslog-ng 376052.582519 RET   read 44/0x2c
 27619 syslog-ng 376052.582533 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 376052.582548 NAMI  "/dev/ttyp0"
 27619 syslog-ng Events dropped.
 27619 syslog-ng 388557.420741 RET   poll -1 errno 4 Interrupted system call
 27619 syslog-ng 388557.420948 PSIG  SIGHUP caught handler=0x8049cc4
mask=0x0 code=0x0
 27619 syslog-ng 388557.421031 CALL  sigaction(0x1,0xbfbfe430,0xbfbfe410)
 27619 syslog-ng 388557.421050 RET   sigaction 0
 27619 syslog-ng 388557.421121 CALL  sigreturn(0xbfbfe4a0)
 27619 syslog-ng 388557.421138 RET   sigreturn JUSTRETURN
 27619 syslog-ng 388557.421211 CALL  poll(0xbfbfe7c0,0x1,0xffffffff)
 27619 syslog-ng 421774.084105 RET   poll 1
 27619 syslog-ng 421774.084275 CALL  write(0xd,0x8062408,0xec)
 27619 syslog-ng 421774.084299 RET   write -1 errno 5 Input/output error
 27619 syslog-ng 421774.084373 CALL  close(0xd)
 27619 syslog-ng 421774.084396 RET   close 0
 27619 syslog-ng 421774.084467 CALL  read(0xc,0x805f540,0x2c)
 27619 syslog-ng 421774.084502 GIO   fd 12 read 44 bytes
       0x0000 7474 7970 3100 0000 0000 0000 0000 0000 0000 0000 0000 0000
0000 0000 0000 0000 0000 0000 0000 0000 b305 a641 
|ttyp1......................................A|
 27619 syslog-ng 421774.084516 RET   read 44/0x2c
 27619 syslog-ng 421774.084701 CALL  open(0xbfbfe800,0x800d,0xbfbfe898)
 27619 syslog-ng 421774.084724 NAMI  "/dev/ttyp1"
 27619 syslog-ng 421774.084884 RET   open 13/0xd
 27619 syslog-ng 421774.085946 CALL  poll(0xbfbfe7c0,0x1,0xffffffff)

regards,
Jost

-- 
NEU +++ DSL Komplett von GMX +++ http://www.gmx.net/de/go/dsl
GMX DSL-Netzanschluss + Tarif zum supergünstigen Komplett-Preis!