Hi all.
I've just put amanda 2.6.1p2 and my existing (and long working) config files
onto a new machine, and tested that it worked to both backup and recover.
Then I uninstalled the 2.6.1p2 and installed amanda 3.3.2
Now I get a broken pipe in the amrecover log, exactly after I answer the
"set owner/mode?" question .... and the amrecover window hangs
until I control-C out of it.
but only if the DLE includes compression. If I turn off compression
and redo the backups, a recover will succeed. Even a recover which
involved 2 tapes.
versions:
tar (GNU tar) 1.23
gzip 1.3.12
Any idea what the problem is?
Deb
Here are the amrecover and amandad logs, since they contain errors.
I have other logs too if you need them, but I don't see any complaints in them.
===============================
amrecover.debug
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: pid 29473 ruid 0 euid 0
version 3.3.2: start at Tue Dec 11 13:11:11 2012
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: config_overrides: conf daily
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: pid 29473 ruid 0 euid 0
version 3.3.2: rename at Tue Dec 11 13:11:11 2012
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: security_getdriver(name=bsd)
returns 0x7fc6596bd2e0
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
security_handleinit(handle=0xfd0260, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: dgram_bind: setting up a
socket with family 2
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: bind_portrange2: Skip port
848: Owned by gdoi.
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: bind_portrange2: Try port
849: Available - Success
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: dgram_bind: socket 3 bound
to 0.0.0.0:849
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
dgram_send_addr(addr=0xfd02a0, dgram=0x7fc6596c9da8)
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: (sockaddr_in *)0xfd02a0 = {
2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: dgram_send_addr:
0x7fc6596c9da8->socket = 3
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
dgram_recv(dgram=0x7fc6596c9da8, timeout=0, fromaddr=0x7fc6596d9da0)
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: (sockaddr_in
*)0x7fc6596d9da0 = { 2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
dgram_recv(dgram=0x7fc6596c9da8, timeout=0, fromaddr=0x7fc6596d9da0)
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: (sockaddr_in
*)0x7fc6596d9da0 = { 2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
dgram_send_addr(addr=0xfd02a0, dgram=0x7fc6596c9da8)
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: (sockaddr_in *)0xfd02a0 = {
2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: dgram_send_addr:
0x7fc6596c9da8->socket = 3
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
security_streaminit(stream=0xfd7840, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: make_socket opening socket
with family 2
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: connect_port: Try port
50000: available - Success
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: connected to
131.225.121.103:50006
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: our side is 0.0.0.0:50000
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover:
security_close(handle=0xfd0260, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: sending: FEATURES
ffffffff9efefbffffffffff1f
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: sending: DATE 2012-12-11
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: sending: SCNF daily
Tue Dec 11 13:11:11 2012: thd-0xfc4490: amrecover: sending: HOST mynode.fqdn
Tue Dec 11 13:11:19 2012: thd-0xfc4490: amrecover: user command: 'setdate
2012-12-06'
Tue Dec 11 13:11:19 2012: thd-0xfc4490: amrecover: sending: DATE 2012-12-06
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: user command: 'setdisk /var'
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: sending: DISK /var
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: sending: OISD /
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: sending: OLSD /
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/."
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/account/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/adm/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/cache/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/crash/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/cvs/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/db/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/empty/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/games/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/gdm/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/lib/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/local/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/lock/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/lost+found/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/mail"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/nis/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/opt/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/preserve/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/run/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/spool/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/tmp/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/yp/"
Tue Dec 11 13:11:24 2012: thd-0xfc4490: amrecover: sending: DLE
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: user command: 'cd log'
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: cd_glob (log) -> ^log$
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: sending: OISD /log
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: sending: OLSD /log
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/."
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/ConsoleKit/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/Xorg.0.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/Xorg.0.log.old"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/Xorg.9.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/Xorg.9.log.old"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/anaconda.ifcfg.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/anaconda.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/anaconda.program.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/anaconda.storage.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/anaconda.syslog"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/anaconda.xlog"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/anaconda.yum.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/audit/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/boot.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/btmp"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/btmp-20121201"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/cron"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/cron-20121118"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/cron-20121125"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/cron-20121202"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/cups/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/dmesg"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/dmesg.old"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/dracut.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/gdm/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/lastlog"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/maillog"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/maillog-20121118"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/maillog-20121125"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/maillog-20121202"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/mcelog"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/messages"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/messages-20121118"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/messages-20121125"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/messages-20121202"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/ntpstats/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/ocsinventory"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/pm-powersave.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/ppp/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/prelink/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/sa/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/samba/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/secure"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/secure-20121118"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/secure-20121125"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/secure-20121202"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/spice-vdagentd/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/spooler"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/spooler-20121118"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/spooler-20121125"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/spooler-20121202"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/sssd/"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/tallylog"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3"
"/log/wpa_supplicant.log"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/wtmp"
Tue Dec 11 13:11:27 2012: thd-0xfc4490: amrecover: add_dir_list_item: Adding
"2012-12-06-12-56-36" "0" "adUXdaily-0daily-827test:3" "3" "/log/yum.log"
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: user command: 'add
pm-powersave.log'
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_glob (pm-powersave.log)
-> ^pm-powersave\.log$
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Looking for
"pm-powersave\.log[/]*$"
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Converted
path="pm-powersave\.log[/]*$" to tpath_on_disk="\/log/pm-powersave\.log[/]*$"
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/yum.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/wtmp
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/wpa_supplicant.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/tallylog
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/sssd/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/spooler-20121202
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/spooler-20121125
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/spooler-20121118
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/spooler
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/spice-vdagentd/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/secure-20121202
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/secure-20121125
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/secure-20121118
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/secure
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/samba/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/sa/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/prelink/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/ppp/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/pm-powersave.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: (Successful) Added
/log/pm-powersave.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/ocsinventory
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/ntpstats/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/messages-20121202
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/messages-20121125
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/messages-20121118
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/messages
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/mcelog
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/maillog-20121202
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/maillog-20121125
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/maillog-20121118
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/maillog
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/lastlog
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/gdm/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/dracut.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/dmesg.old
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/dmesg
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/cups/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/cron-20121202
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/cron-20121125
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/cron-20121118
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/cron
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/btmp-20121201
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/btmp
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/boot.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/audit/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.yum.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.xlog
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.syslog
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.storage.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.program.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/anaconda.ifcfg.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/Xorg.9.log.old
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/Xorg.9.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/Xorg.0.log.old
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/Xorg.0.log
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/ConsoleKit/
Tue Dec 11 13:11:34 2012: thd-0xfc4490: amrecover: add_file: Pondering
ditem->path=/log/.
Tue Dec 11 13:11:36 2012: thd-0xfc4490: amrecover: user command: 'list'
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover: user command: 'extract'
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover: sending: TAPE
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover:
append_to_tapelist(tapelist=(nil), label='adUXdaily-0daily-827test', file=-1,
partnum=-1, isafile=0)
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover:
append_to_tapelist(tapelist=0xfe7b20, label='adUXdaily-0daily-827test', file=3,
partnum=-1, isafile=0)
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover:
append_to_tapelist(tapelist=(nil), label='adUXdaily-0daily-827test', file=-1,
partnum=-1, isafile=0)
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover:
append_to_tapelist(tapelist=0xfe7c00, label='adUXdaily-0daily-827test', file=3,
partnum=-1, isafile=0)
Tue Dec 11 13:11:48 2012: thd-0xfc4490: amrecover: Requesting tape
adUXdaily-0daily-827test from user
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: User prompt: 'Continue
[?/Y/n/s/d]? '; response: 'y'
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: security_getdriver(name=bsd)
returns 0x7fc6596bd2e0
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
security_handleinit(handle=0xfe8520, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
dgram_send_addr(addr=0xfe8560, dgram=0x7fc6596c9da8)
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: (sockaddr_in *)0xfe8560 = {
2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: dgram_send_addr:
0x7fc6596c9da8->socket = 3
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
dgram_recv(dgram=0x7fc6596c9da8, timeout=0, fromaddr=0x7fc6596d9da0)
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: (sockaddr_in
*)0x7fc6596d9da0 = { 2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
dgram_recv(dgram=0x7fc6596c9da8, timeout=0, fromaddr=0x7fc6596d9da0)
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: (sockaddr_in
*)0x7fc6596d9da0 = { 2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
dgram_send_addr(addr=0xfe8560, dgram=0x7fc6596c9da8)
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: (sockaddr_in *)0xfe8560 = {
2, 10080, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: dgram_send_addr:
0x7fc6596c9da8->socket = 3
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
security_streaminit(stream=0xff4190, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: make_socket opening socket
with family 2
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: connect_port: Try port
50000: available - Success
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: connected to
131.225.121.103:50044
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: our side is 0.0.0.0:50000
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: amidxtaped_streams[0].fd =
0xff4190
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
security_streaminit(stream=0xffc1f0, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: make_socket opening socket
with family 2
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: connect_port: Try port
50000: available - Success
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: connected to
131.225.121.103:50045
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: our side is 0.0.0.0:50000
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: amidxtaped_streams[1].fd =
0xffc1f0
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover:
security_close(handle=0xfe8520, driver=0x7fc6596bd2e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
FEATURES=ffffffff9efefbffffffffff1f
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
CONFIG=daily
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
LABEL=adUXdaily-0daily-827test:3
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server: FSF=3
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server: HEADER
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
DEVICE=MyRobot
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
HOST=^mynode.fqdn$
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
DISK=^/var$
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server:
DATESTAMP=20121206125636
Tue Dec 11 13:11:49 2012: thd-0xfc4490: amrecover: send_to_tape_server: END
Tue Dec 11 13:12:20 2012: thd-0xfc4490: amrecover: read header 32768 => 32768
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: User prompt: 'Continue
[?/Y/n]? '; response: 'y'
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: send_to_tape_server:
AVAIL-DATAPATH AMANDA
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: get amidxtaped line:
USE-DATAPATH AMANDA
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: Using AMANDA data-path
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: image is compressed
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: Spawning "/bin/gzip
/bin/gzip -dc" in pipeline
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: send_to_tape_server:
DATAPATH-OK
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: Exec'ing /sbin/restore with
arguments:
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: restore
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: xbf
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: 2
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: -
Tue Dec 11 13:12:59 2012: thd-0xfc4490: amrecover: /log/pm-powersave.log
gzip: stdout: Broken pipe
Tue Dec 11 13:21:04 2012: thd-0xfc4490: amrecover: sending: QUIT
===============================
amandad.debug
mynode.fqdnTue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: pid 29474 ruid 11
euid 11 version 3.3.2: start at Tue Dec 11 13:11:11 2012
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: security_getdriver(name=bsd)
returns 0x7f58a94852e0
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: version 3.3.2
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: build:
VERSION="Amanda-3.3.2"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: BUILT_DATE="Fri
Dec 7 16:01:17 CST 2012" BUILT_MACH=""
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: BUILT_REV="4847"
BUILT_BRANCH="community_3_3_2" CC="gcc"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: paths:
bindir="/usr/local/bin" sbindir="/usr/local/sbin"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
libexecdir="/usr/local/libexec"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
amlibexecdir="/usr/local/libexec/amanda"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
mandir="/usr/local/share/man" AMANDA_TMPDIR="/tmp/amanda"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
AMANDA_DBGDIR="/tmp/amanda"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
CONFIG_DIR="/usr/local/etc/amanda" DEV_PREFIX="/dev/"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: XFSDUMP=UNDEF
XFSRESTORE=UNDEF VXDUMP=UNDEF VXRESTORE=UNDEF
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: LPRCMD=UNDEF
MAILER=UNDEF
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
listed_incr_dir="/usr/local/var/amanda/gnutar-lists"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: defs:
DEFAULT_SERVER="mynode" DEFAULT_CONFIG="daily"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
DEFAULT_TAPE_SERVER="mynode"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
DEFAULT_TAPE_DEVICE="tape:/dev/nst0" NEED_STRSTR
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: AMFLOCK_POSIX
AMFLOCK_FLOCK AMFLOCK_LOCKF AMFLOCK_LNLOCK
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: SETPGRP_VOID
AMANDA_DEBUG_DAYS=4 BSD_SECURITY USE_AMANDAHOSTS
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
CLIENT_LOGIN="operator" CHECK_USERID HAVE_GZIP
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
dgram_recv(dgram=0x7f58a9491da8, timeout=0, fromaddr=0x7f58a94a1da0)
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x7f58a94a1da0
= { 2, 849, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
security_handleinit(handle=0x11e15f0, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: authenticated peer name is
'mynode.fqdn'
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: accept recv REQ pkt:
<<<<<
SERVICE amindexd
OPTIONS features=ffffffff9efefbffffffffff1f;auth=bsd;
>>>>>
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: creating new service: amindexd
OPTIONS features=ffffffff9efefbffffffffff1f;auth=bsd;
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: sending ACK pkt:
<<<<<
>>>>>
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
dgram_send_addr(addr=0x11e1630, dgram=0x7f58a9491da8)
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x11e1630 = {
2, 849, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: dgram_send_addr:
0x7f58a9491da8->socket = 0
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
security_streaminit(stream=0x11f2d60, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: stream_server opening socket
with family 2 (requested family was 2)
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: bind_portrange2: Try port
50006: Available - Success
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: stream_server: waiting for
connection: 0.0.0.0:50006
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: sending REP pkt:
<<<<<
CONNECT MESG 50006
>>>>>
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
dgram_send_addr(addr=0x11e1630, dgram=0x7f58a9491da8)
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x11e1630 = {
2, 849, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: dgram_send_addr:
0x7f58a9491da8->socket = 0
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
dgram_recv(dgram=0x7f58a9491da8, timeout=0, fromaddr=0x7f58a94a1da0)
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x7f58a94a1da0
= { 2, 849, 131.225.121.103 }
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: received ACK pkt:
<<<<<
>>>>>
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: opening security stream for
fd 50
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: stream_accept: connection
from 131.225.121.103:50000
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:11 2012: thd-0x11d54b0: amandad:
security_close(handle=0x11e15f0, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
dgram_recv(dgram=0x7f58a9491da8, timeout=0, fromaddr=0x7f58a94a1da0)
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x7f58a94a1da0
= { 2, 849, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
security_handleinit(handle=0x11e15f0, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: authenticated peer name is
'mynode.fqdn'
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: accept recv REQ pkt:
<<<<<
SERVICE amidxtaped
OPTIONS features=ffffffff9efefbffffffffff1f;auth=bsd;
>>>>>
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: creating new service:
amidxtaped
OPTIONS features=ffffffff9efefbffffffffff1f;auth=bsd;
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: sending ACK pkt:
<<<<<
>>>>>
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
dgram_send_addr(addr=0x11e1630, dgram=0x7f58a9491da8)
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x11e1630 = {
2, 849, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: dgram_send_addr:
0x7f58a9491da8->socket = 0
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
security_streaminit(stream=0x120ba30, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: stream_server opening socket
with family 2 (requested family was 2)
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: bind_portrange2: Try port
50044: Available - Success
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: stream_server: waiting for
connection: 0.0.0.0:50044
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
security_streaminit(stream=0x1213a90, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: stream_server opening socket
with family 2 (requested family was 2)
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: bind_portrange2: Try port
50044: Available - Address already in use
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: bind_portrange2: Try port
50045: Available - Success
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: stream_server: waiting for
connection: 0.0.0.0:50045
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: sending REP pkt:
<<<<<
CONNECT CTL 50044 DATA 50045
>>>>>
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
dgram_send_addr(addr=0x11e1630, dgram=0x7f58a9491da8)
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x11e1630 = {
2, 849, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: dgram_send_addr:
0x7f58a9491da8->socket = 0
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
dgram_recv(dgram=0x7f58a9491da8, timeout=0, fromaddr=0x7f58a94a1da0)
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: (sockaddr_in *)0x7f58a94a1da0
= { 2, 849, 131.225.121.103 }
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: received ACK pkt:
<<<<<
>>>>>
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: opening security stream for
fd 50
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: stream_accept: connection
from 131.225.121.103:50000
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: opening security stream for
fd 51
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: stream_accept: connection
from 131.225.121.103:50000
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: send buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad: try_socksize: receive buffer
size is 65536
Tue Dec 11 13:11:49 2012: thd-0x11d54b0: amandad:
security_close(handle=0x11e15f0, driver=0x7f58a94852e0 (BSD))
Tue Dec 11 13:21:04 2012: thd-0x11d54b0: amandad:
security_stream_seterr(0x1213a90, write error on stream 50045: Connection reset
by peer)
Tue Dec 11 13:21:04 2012: thd-0x11d54b0: amandad: sending NAK pkt:
<<<<<
ERROR write error on stream 50045: write error on stream 50045: Connection
reset by peer
>>>>>
Tue Dec 11 13:21:04 2012: thd-0x11d54b0: amandad:
security_stream_close(0x120ba30)
Tue Dec 11 13:21:04 2012: thd-0x11d54b0: amandad:
security_stream_close(0x1213a90)
Tue Dec 11 13:21:05 2012: thd-0x11d54b0: amandad:
security_stream_close(0x11f2d60)
Tue Dec 11 13:21:35 2012: thd-0x11d54b0: amandad: pid 29474 finish time Tue Dec
11 13:21:35 2012
