Re: FATAL Did not get DATE line from planner

Il giorno gio, 19/10/2006 alle 09.52 -0400, Jean-Louis Martineau ha
scritto:
> amanda generate a lot of debug and log files, have you looked at them?
> 
> What's in amdump.1 and planner.<timestamp>.debug?
[...]

I did many tries with amcheck and amdump and found very often a second
problem that give a message "timeout waiting ack". So actually I cannot
find the correct log for this error, but I have a
planner.20061019125126.debug file that is:

planner: debug 1 pid 32288 ruid 37 euid 0: start at Thu Oct 19 12:51:26 2006
planner: debug 1 pid 32288 ruid 37 euid 37: rename at Thu Oct 19 12:51:26 2006
security_getdriver(name=BSD) returns 0x2a959da100
security_handleinit(handle=0x51d110, driver=0x2a959da100 (BSD))
planner: time 0.006: bind_portrange2: Try  port 718: Available   - Success
planner: time 0.006: dgram_bind: socket bound to 0.0.0.0.718
planner: dgram_send_addr(addr=0x7fbfffb4f0, dgram=0x2a959df0e8)
planner: time 0.009: (sockaddr_in *)0x7fbfffb4f0 = { 2, 10080, 127.0.0.1 }
planner: dgram_send_addr: 0x2a959df0e8->socket = 3
planner: dgram_send_addr(addr=0x7fbfffcc90, dgram=0x2a959df0e8)
planner: time 10.007: (sockaddr_in *)0x7fbfffcc90 = { 2, 10080, 127.0.0.1 }
planner: dgram_send_addr: 0x2a959df0e8->socket = 3
planner: dgram_send_addr(addr=0x7fbfffcc90, dgram=0x2a959df0e8)
planner: time 20.005: (sockaddr_in *)0x7fbfffcc90 = { 2, 10080, 127.0.0.1 }
planner: dgram_send_addr: 0x2a959df0e8->socket = 3
security_seterror(handle=0x51d110, driver=0x2a959da100 (BSD) error=timeout 
waiting for ACK)
security_close(handle=0x51d110, driver=0x2a959da100 (BSD))
planner: time 30.002: pid 32288 finish time Thu Oct 19 12:51:56 2006

There aren't any amdump.* file in /tmp/amanda

I then tried:

# su amanda -c '/usr/local/sbin/amrmtape savio SAVIO_00'
# su amanda -c '/usr/local/sbin/amlabel savio SAVIO_00 slot 2
# rm -fr /tmp/amanda
# su amanda -c '/usr/local/sbin/amdump savio'

and got these files:

-rw-r-----  1 amanda disk  216 Oct 19 16:07 amlogroll.20061019160735.debug
-rw-r-----  1 amanda disk  213 Oct 19 16:07 amreport.20061019160735.debug
-rw-r-----  1 amanda disk  982 Oct 19 16:07 amtrmidx.20061019160735.debug
-rw-r-----  1 amanda disk  288 Oct 19 16:07 amtrmlog.20061019160735.debug
-rw-r-----  1 amanda disk 1465 Oct 19 16:07 planner.20061019160705.debug

The planner* one contains:

planner: debug 1 pid 2150 ruid 37 euid 0: start at Thu Oct 19 16:07:05 2006
planner: debug 1 pid 2150 ruid 37 euid 37: rename at Thu Oct 19 16:07:05 2006
security_getdriver(name=BSD) returns 0x2a959da1e0
security_handleinit(handle=0x51d110, driver=0x2a959da1e0 (BSD))
planner: time 0.003: bind_portrange2: Skip port 751: Owned by pump.
planner: time 0.003: bind_portrange2: Skip port 752: Owned by qrh.
planner: time 0.004: bind_portrange2: Skip port 753: Owned by rrh.
planner: time 0.004: bind_portrange2: Skip port 754: Owned by tell.
planner: time 0.008: bind_portrange2: Try  port 755: Available   - Success
planner: time 0.008: dgram_bind: socket bound to 0.0.0.0.755
planner: dgram_send_addr(addr=0x7fbfffb500, dgram=0x2a959df1c8)
planner: time 0.011: (sockaddr_in *)0x7fbfffb500 = { 2, 10080, 127.0.0.1 }
planner: dgram_send_addr: 0x2a959df1c8->socket = 3
planner: dgram_send_addr(addr=0x7fbfffcca0, dgram=0x2a959df1c8)
planner: time 10.009: (sockaddr_in *)0x7fbfffcca0 = { 2, 10080, 127.0.0.1 }
planner: dgram_send_addr: 0x2a959df1c8->socket = 3
planner: dgram_send_addr(addr=0x7fbfffcca0, dgram=0x2a959df1c8)
planner: time 20.008: (sockaddr_in *)0x7fbfffcca0 = { 2, 10080, 127.0.0.1 }
planner: dgram_send_addr: 0x2a959df1c8->socket = 3
security_seterror(handle=0x51d110, driver=0x2a959da1e0 (BSD) error=timeout 
waiting for ACK)
security_close(handle=0x51d110, driver=0x2a959da1e0 (BSD))
planner: time 30.006: pid 2150 finish time Thu Oct 19 16:07:35 2006

and the error message, in the root mailbox, is:

*** THE DUMPS DID NOT FINISH PROPERLY!

The next tape Amanda expects to use is: a new tape.
The next new tape already labelled is: SAVIO_00.

FAILURE AND STRANGE DUMP SUMMARY:
  localhost  /            RESULTS MISSING
  localhost  /home        RESULTS MISSING
  localhost  /usr         RESULTS MISSING
  localhost  /opt/baan    RESULTS MISSING
  localhost  /opt/baanIV  RESULTS MISSING
  planner: ERROR Request to localhost failed: timeout waiting for ACK