PDA

View Full Version : FAIL dumper [port open: Resource temporarily unavailable]



chrs
September 28th, 2008, 11:34 PM
Im using amanda (openSUSE11) to make a backup on Tape HP DAT, after $ amdump fullback in the log file i can see the following error :

----------------------------------------------------------------------
DISK planner NetFS.keeenap.gr /home/test
START planner date 20080926
INFO planner Adding new disk NetFS.keeenap.gr:/home/test.
START driver date 20080926
STATS driver hostname NetFS
WARNING driver WARNING: This is not the first amdump run today. Enable the usetimestamps option in the configuration file if you want to run amdump more than once per calendar day.
STATS driver startup time 0.003
START taper datestamp 20080926 label FULLBACK-01 tape 0
FINISH planner date 20080926 time 0.035
FAIL dumper NetFS.keeenap.gr /home/test 20080926 0 [port open: Resource temporarily unavailable]
FATAL chunker startup_chunker failed
FAIL dumper NetFS.keeenap.gr /home/test 20080926 0 [port open: Resource temporarily unavailable]
FATAL chunker startup_chunker failed
INFO taper tape FULLBACK-01 kb 0 fm 0 [OK]
FINISH driver date 20080926 time 616.043

-----------------------------------------------------------------

Also in the amdump.1 log file :

---------------------------------------------------------------------
amdump: start at Fri Sep 26 16:02:27 EEST 2008
amdump: datestamp 20080926
amdump: starttime 20080926160227
planner: pid 26540 executable /usr/lib/amanda/planner version 2.5.2p1
planner: build: VERSION="Amanda-2.5.2p1"
planner: BUILT_DATE="Sat Jun 7 06:52:44 UTC 2008"
planner: BUILT_MACH="Linux brubeck 2.6.25 #1 SMP Thu May 17 14:00:09 UTC 2007 x86_64 x86_64 x86_64 GNU/Linux"
planner: CC="gcc" CONFIGURE_COMMAND="'./configure' 'linux' 'gnu'"
planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
planner: libexecdir="/usr/lib/amanda" mandir="/usr/share/man"
planner: AMANDA_TMPDIR="/tmp/amanda" AMANDA_DBGDIR="/tmp/amanda"
planner: CONFIG_DIR="/etc/amanda" DEV_PREFIX="/dev/"
planner: RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
planner: RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
planner: XFSDUMP=UNDEF XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
planner: SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
planner: COMPRESS_PATH="/usr/bin/gzip"
planner: UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lpr"
planner: MAILER="/usr/bin/Mail"
planner: listed_incr_dir="/var/lib/amanda/gnutar-lists"
planner: defs: DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
planner: DEFAULT_TAPE_SERVER="localhost" HAVE_MMAP NEED_STRSTR
planner: HAVE_SYSVSHM LOCKING=POSIX_FCNTL SETPGRP_VOID DEBUG_CODE
planner: AMANDA_DEBUG_DAYS=4 BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS
planner: CLIENT_LOGIN="amanda" FORCE_USERID HAVE_GZIP
planner: COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
planner: COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
READING CONF FILES...
driver: pid 26541 executable /usr/lib/amanda/driver version 2.5.2p1
planner: timestamp 20080926
planner: time 0.001: startup took 0.001 secs

SENDING FLUSHES...
ENDFLUSH

SETTING UP FOR ESTIMATES...
planner: time 0.001: setting up estimates for NetFS.keeenap.gr:/home/test
NetFS.keeenap.gr:/home/test overdue 14149 days for level 0
setup_estimate: NetFS.keeenap.gr:/home/test: command 0, options: none last_level -1 next_level0 -14149 level_days 0 getting estimates 0 (-2) -1 (-2) -1 (-2)
planner: time 0.001: setting up estimates took 0.000 secs

GETTING ESTIMATES...
driver: tape size 30574592
driver: adding holding disk 0 dir /backup/amandahold/fullback size 8192000 chunksize 1048576
reserving 8192000 out of 8192000 for degraded-mode dumps
driver: send-cmd time 0.003 to taper: START-TAPER 20080926
driver: started dumper0 pid 26543
driver: send-cmd time 0.003 to dumper0: START 20080926
driver: started dumper1 pid 26544
driver: send-cmd time 0.003 to dumper1: START 20080926
driver: start time 0.003 inparallel 2 bandwidth 2400 diskspace 8192000 dir OBSOLETE datestamp 20080926 driver: drain-ends tapeq FIRST big-dumpers ttt
taper: pid 26542 executable taper version 2.5.2p1
taper: page size = 4096
taper: buffer size is 32768
dumper: pid 26543 executable dumper0 version 2.5.2p1
dumper: pid 26544 executable dumper1 version 2.5.2p1
planner: time 0.022: got partial result for host NetFS.keeenap.gr disk /home/test: 0 -> -2K, -1 -> -2K, -1 -> -2K
taper: wrote label `FULLBACK-01' date `20080926'
driver: result time 0.029 from taper: TAPER-OK
driver: state time 0.029 free kps: 2400 space: 8192000 taper: idle idle-dumpers: 2 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
driver: interface-state time 0.029 if default: free 600 if local: free 1000 if eth0: free 800
driver: hdisk-state time 0.029 hdisk 0: free 8192000 dumpers 0
planner: time 0.034: got partial result for host NetFS.keeenap.gr disk /home/test: 0 -> 10K, -1 -> -2K, -1 -> -2K
planner: time 0.035: got result for host NetFS.keeenap.gr disk /home/test: 0 -> 10K, -1 -> -2K, -1 -> -2K
planner: time 0.035: getting estimates took 0.033 secs
FAILED QUEUE: empty
DONE QUEUE:
0: NetFS.keeenap.gr /home/test

ANALYZING ESTIMATES...
pondering NetFS.keeenap.gr:/home/test... next_level0 -14149 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
curr level 0 nsize 10 csize 5 total size 401 total_lev0 5 balanced-lev0size 0
INITIAL SCHEDULE (size 401):
NetFS.keeenap.gr /home/test pri 14150 lev 0 nsize 10 csize 5

DELAYING DUMPS IF NEEDED, total_size 401, tape length 30574592 mark 100
delay: Total size now 401.

PROMOTING DUMPS IF NEEDED, total_lev0 5, balanced_size 0...
planner: time 0.035: analysis took 0.000 secs

GENERATING SCHEDULE:
--------
DUMP NetFS.keeenap.gr ffffffff9ffeffffffff00 /home/test 20080926 14150 0 1970:1:1:0:0:0 10 5 0 1024
--------
driver: flush size 0
driver: started chunker0 pid 26554
driver: send-cmd time 0.035 to chunker0: START 20080926
driver: send-cmd time 0.035 to chunker0: PORT-WRITE 00-00001 /backup/amandahold/fullback/20080926160227/NetFS.keeenap.gr._home_test.0 NetFS.keeenap.gr ffffffff9ffeffffffff00 /home/test 0 1970:1:1:0:0:0 1048576 GNUTAR 96 |;auth=BSD;compress-fast;index;
chunker: pid 26554 executable chunker0 version 2.5.2p1
driver: result time 0.039 from chunker0: PORT 25832
driver: send-cmd time 0.039 to dumper0: PORT-DUMP 00-00001 25832 NetFS.keeenap.gr ffffffff9ffeffffffff00 /home/test NODEVICE 0 1970:1:1:0:0:0 GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 0.039 free kps: 1376 space: 8191904 taper: idle idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 0.039 if default: free 0 if local: free 1000 if eth0: free 800
driver: hdisk-state time 0.039 hdisk 0: free 8191904 dumpers 1
driver: result time 0.039 from dumper0: FAILED 00-00001 "port open: Resource temporarily unavailable"
driver: send-cmd time 0.039 to chunker0: FAILED 00-00001
chunker: startup_chunker failed
driver: state time 300.037 free kps: 1376 space: 8191904 taper: idle idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 300.037 if default: free 0 if local: free 1000 if eth0: free 800
driver: hdisk-state time 300.037 hdisk 0: free 8191904 dumpers 1
driver: result time 300.037 from chunker0: TRY-AGAIN 00-00001 "[chunker startup failed: error accepting stream: No such file or directory]"
rename_tmp_holding: open of /backup/amandahold/fullback/20080926160227/NetFS.keeenap.gr._home_test.0.tmp failed: No such file or directory
driver: started chunker0 pid 26611
driver: send-cmd time 300.038 to chunker0: START 20080926
driver: send-cmd time 300.038 to chunker0: PORT-WRITE 00-00002 /backup/amandahold/fullback/20080926160227/NetFS.keeenap.gr._home_test.0 NetFS.keeenap.gr ffffffff9ffeffffffff00 /home/test 0 1970:1:1:0:0:0 1048576 GNUTAR 96 |;auth=BSD;compress-fast;index;
chunker: pid 26611 executable chunker0 version 2.5.2p1
driver: result time 300.042 from chunker0: PORT 8608
driver: send-cmd time 300.042 to dumper0: PORT-DUMP 00-00002 8608 NetFS.keeenap.gr ffffffff9ffeffffffff00 /home/test NODEVICE 0 1970:1:1:0:0:0 GNUTAR X X X |;auth=BSD;compress-fast;index;
driver: state time 300.042 free kps: 1376 space: 8191904 taper: idle idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 300.042 if default: free 0 if local: free 1000 if eth0: free 800
driver: hdisk-state time 300.042 hdisk 0: free 8191904 dumpers 1
driver: result time 300.042 from dumper0: FAILED 00-00002 "port open: Resource temporarily unavailable"
driver: send-cmd time 300.042 to chunker0: FAILED 00-00002
chunker: startup_chunker failed
driver: state time 600.042 free kps: 1376 space: 8191904 taper: idle idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 600.042 if default: free 0 if local: free 1000 if eth0: free 800
driver: hdisk-state time 600.042 hdisk 0: free 8191904 dumpers 1
driver: result time 600.042 from chunker0: TRY-AGAIN 00-00002 "[chunker startup failed: error accepting stream: No such file or directory]"
rename_tmp_holding: open of /backup/amandahold/fullback/20080926160227/NetFS.keeenap.gr._home_test.0.tmp failed: No such file or directory
driver: state time 600.042 free kps: 2400 space: 8192000 taper: idle idle-dumpers: 2 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
driver: interface-state time 600.042 if default: free 600 if local: free 1000 if eth0: free 800
driver: hdisk-state time 600.042 hdisk 0: free 8192000 dumpers 0
driver: QUITTING time 600.042 telling children to quit
driver: send-cmd time 600.042 to dumper0: QUIT
driver: send-cmd time 600.042 to dumper1: QUIT
driver: send-cmd time 600.043 to taper: QUIT
taper: DONE [idle wait: 600.013 secs]
taper: writing end marker. [FULLBACK-01 OK kb 0 fm 0]
driver: FINISHED time 616.043
amdump: end at Fri Sep 26 16:12:43 EEST 2008

-------------------------------------------------------------------------
Attach you may find the amanda.conf
Please provide me any help

Thank you
Christos

martineau
September 29th, 2008, 03:29 AM
What is the output of: amgetconf <conf> unreserved-tcp-port
Add 'debug auth 1' to yout amanda.conf and retry.
Post the chunker.*.debug and dumper.*.debug files.

chrs
September 29th, 2008, 04:52 AM
--What is the output of: amgetconf <conf> unreserved-tcp-port

The output was: 0,0

After making an -- amcheck fullback i got: selfcheck request failed: timeout waiting for ACK

--------------------------------------------------------------------------
Amanda Tape Server Host Check
-----------------------------
Holding disk /backup/amandahold/fullback: 11525576 kB disk space available, using 8192000 kB as requested
read label `FULLBACK-01', date `X'
NOTE: skipping tape-writable test
Tape FULLBACK-01 label ok
NOTE: host info dir /etc/amanda/fullback/curinfo/NetFS.keeenap.gr does not exist
NOTE: it will be created on the next run.
NOTE: index dir /etc/amanda/fullback/index/NetFS.keeenap.gr does not exist
NOTE: it will be created on the next run.
Server check took 0.009 seconds

Amanda Backup Client Hosts Check
--------------------------------
WARNING: NetFS.keeenap.gr: selfcheck request failed: timeout waiting for ACK
Client check: 1 host checked in 30.007 seconds, 1 problem found

(brought to you by Amanda 2.5.2p1)
amanda@NetFS:/root> selfcheck request failed: timeout waiting for ACKselfcheck request failed: timeout waiting for ACK
bash: selfcheck: command not found
amanda@NetFS:/root>
amanda@NetFS:/root> amcheck fullback
Amanda Tape Server Host Check
-----------------------------
Holding disk /backup/amandahold/fullback: 11525512 kB disk space available, using 8192000 kB as requested
read label `FULLBACK-01', date `X'
NOTE: skipping tape-writable test
Tape FULLBACK-01 label ok
NOTE: host info dir /etc/amanda/fullback/curinfo/NetFS.keeenap.gr does not exist
NOTE: it will be created on the next run.
NOTE: index dir /etc/amanda/fullback/index/NetFS.keeenap.gr does not exist
NOTE: it will be created on the next run.
Server check took 0.009 seconds

Amanda Backup Client Hosts Check
--------------------------------
WARNING: NetFS.keeenap.gr: selfcheck request failed: timeout waiting for ACK
Client check: 1 host checked in 30.008 seconds, 1 problem found

(brought to you by Amanda 2.5.2p1)
--------------------------------------------------------------------------

----Post the chunker.*.debug and dumper.*.debug files.

Where those files located?


Thank you
Christos

martineau
September 29th, 2008, 05:08 AM
--What is the output of: amgetconf <conf> unreserved-tcp-port

The output was: 0,0


reconfigure amanda with a valid --with-tcpportrange, or add "unreserved-tcp-port 1024,65536" to your amanda.conf, if your release allow it.



After making an -- amcheck fullback i got: selfcheck request failed: timeout waiting for ACK

That's another issue, I can't believe the planner already succeed and now amcheck fail that way, what did you change? xinetd, auth, ...?




----Post the chunker.*.debug and dumper.*.debug files.

Where those files located?

In the configure debug directory, it default to /tmp/amanda
You can find your configured value with: amgetconf fullback build.AMANDA_DBGDIR

chrs
September 29th, 2008, 06:18 AM
You are right i have changed the /etc/xinetd.d/amanda,
but while i bring it back as it was and restart the xinetd

I have the following issue :


NetFS:~ # service xinetd restart
Shutting down xinetd: done
Starting INET services. (xinetd) failed


chunker.20080926160727.debug

chunker: debug 1 pid 26611 ruid 37 euid 37: start at Fri Sep 26 16:07:27 2008
chunker: debug 1 pid 26611 ruid 37 euid 37: rename at Fri Sep 26 16:07:27 2008
chunker: time 0.002: try_socksize: receive buffer size is 65536
chunker: time 0.002: stream_server: waiting for connection: ::.8608
chunker: time 300.002: stream_accept: timeout after 300 seconds
chunker: time 300.002: startup_chunker failed
chunker: time 300.002: pid 26611 finish time Fri Sep 26 16:12:27 2008

dumper.20080929155529000.debug

dumper: debug 1 pid 4964 ruid 37 euid 0: start at Mon Sep 29 15:55:29 2008
dumper: debug 1 pid 4964 ruid 37 euid 0: rename at Mon Sep 29 15:55:29 2008
dumper: time 29.991: pid 4964 finish time Mon Sep 29 15:55:59 2008

pyeatman
October 2nd, 2008, 04:18 PM
Not sure how to read the chunker/dumper debug files but as for your xinetd not restarting, I would try a stop and start and, if unsuccessful, tail /var/log/messages for more detail. If something is incorrect in the amanda xinetd file, system logs should point this out in particular and likely even give you line numbers.

Paul