Re: [rdiff-backup-users] cron job opens and closes ssh without copying.

[Chris Fanning]

Hi,

Roland.
(or put backup.sh >/tmp/backup.log in crontab)
/tmp/backup.log empty :(

Dean,
> do you get any mail from cron?  usually you should get some mail with
> stdout/stderr in it... i'm guessing it would help diagnose this...

Here goes..

> From root  Wed Jun  7 09:32:02 2006
Date: Wed, 7 Jun 2006 09:32:01 +0200
From: address@hidden (Cron Daemon)
To: address@hidden
Subject: Cron <address@hidden> /usr/bin/rdiff-backup -v 9
address@hidden::/home/pas /copies/xen1-bcn/pas
X-Cron-Env: <SHELL=/bin/sh>
X-Cron-Env: <HOME=/root>
X-Cron-Env: <PATH=/usr/bin:/bin>
X-Cron-Env: <LOGNAME=root>

Wed Jun  7 09:23:54 2006  Server sending (0): None
Wed Jun  7 09:23:54 2006  Server received (0): ConnectionRequest:
log.Log.setterm_verbosity with 1 arguments
Wed Jun  7 09:23:54 2006  Server received (0): 9
Wed Jun  7 09:23:54 2006  Server sending (0): None
Wed Jun  7 09:23:54 2006  Server received (0): ConnectionRequest:
C.make_file_dict with 1 arguments
Wed Jun  7 09:23:54 2006  Server received (0): '/home/pas'
Wed Jun  7 09:23:54 2006  Server sending (0): {'uid': 0, 'perms': 493,
'type': 'dir', 'ctime': 1149237306, 'devloc': 2307L, 'nlink': 10, 'gid
': 0, 'mtime': 1149237306, 'atime': 1149664915, 'inode': 14745601L,
'size': 4096L}
Wed Jun  7 09:23:54 2006  Server received (0): ConnectionRequest:
rpath.setdata_local with 1 arguments
Wed Jun  7 09:23:54 2006  Server received (0): Path: /home/pas
Index: ()
Data: {'size': 4096L, 'ctime': 1149237306, 'perms': 493, 'inode':
14745601L, 'devloc': 2307L, 'nlink': 10, 'mtime': 1149237306, 'gid':
0, 'at
ime': 1149664915, 'type': 'dir', 'uid': 0}
Wed Jun  7 09:23:54 2006  Server sending (0): None
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
C.make_file_dict with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): '/home/pas'
Wed Jun  7 09:23:55 2006  Server sending (0): {'uid': 0, 'perms': 493,
'type': 'dir', 'ctime': 1149237306, 'devloc': 2307L, 'nlink': 10, 'gid
': 0, 'mtime': 1149237306, 'atime': 1149664915, 'inode': 14745601L,
'size': 4096L}
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
rpath.setdata_local with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): Path: /home/pas
Index: ()
Data: {'size': 4096L, 'ctime': 1149237306, 'perms': 493, 'inode':
14745601L, 'devloc': 2307L, 'nlink': 10, 'mtime': 1149237306, 'gid':
0, 'at
ime': 1149664915, 'type': 'dir', 'uid': 0}
Wed Jun  7 09:23:55 2006  Server sending (0): None
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
C.make_file_dict with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): '/home/pas'
Wed Jun  7 09:23:55 2006  Server sending (0): {'uid': 0, 'perms': 493,
'type': 'dir', 'ctime': 1149237306, 'devloc': 2307L, 'nlink': 10, 'gid
': 0, 'mtime': 1149237306, 'atime': 1149664915, 'inode': 14745601L,
'size': 4096L}
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
rpath.setdata_local with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): Path: /home/pas
Index: ()
Data: {'size': 4096L, 'ctime': 1149237306, 'perms': 493, 'inode':
14745601L, 'devloc': 2307L, 'nlink': 10, 'mtime': 1149237306, 'gid':
0, 'at
ime': 1149664915, 'type': 'dir', 'uid': 0}
Wed Jun  7 09:23:55 2006  Server sending (0): None
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
os.listdir with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): '/home/pas'
Wed Jun  7 09:23:55 2006  Server sending (0): ['chrisf', 'ferranc',
'martac', 'silviab', 'juliaa', 'montser', 'rosab', 'jordic']
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
Globals.get with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): 'restrict_path'
Wed Jun  7 09:23:55 2006  Server sending (0): None
Wed Jun  7 09:23:55 2006  Server received (0): ConnectionRequest:
C.make_file_dict with 1 arguments
Wed Jun  7 09:23:55 2006  Server received (0): '/home'
Wed Jun  7 09:23:55 2006  Server sending (0): {'uid': 0, 'perms': 493,
'type': 'dir', 'ctime': 1148969509, 'devloc': 2307L, 'nlink': 5, 'gid'
: 0, 'mtime': 1148969509, 'atime': 1149664443, 'inode': 2L, 'size': 4096L}
Traceback (most recent call last):
 File "/usr/bin/rdiff-backup", line 24, in ?
   rdiff_backup.Main.Main(sys.argv[1:])
 File "/usr/lib/python2.2/site-packages/rdiff_backup/Main.py", line
283, in Main
   final_set_action(rps)
 File "/usr/lib/python2.2/site-packages/rdiff_backup/Main.py", line
216, in final_set_action
   if restore_set_root(rps[0]): action = "restore"
 File "/usr/lib/python2.2/site-packages/rdiff_backup/Main.py", line
650, in restore_set_root
   parent_dir = rpath.RPath(rpin.conn, "/".join(pathcomps[:i]))
 File "/usr/lib/python2.2/site-packages/rdiff_backup/rpath.py", line
674, in __init__
   else: self.setdata()
 File "/usr/lib/python2.2/site-packages/rdiff_backup/rpath.py", line
698, in setdata
   self.data = self.conn.C.make_file_dict(self.path)
 File "/usr/lib/python2.2/site-packages/rdiff_backup/connection.py",
line 445, in __call__
   return apply(self.connection.reval, (self.name,) + args)
 File "/usr/lib/python2.2/site-packages/rdiff_backup/connection.py",
line 365, in reval
   result = self.get_response(req_num)
 File "/usr/lib/python2.2/site-packages/rdiff_backup/connection.py",
line 314, in get_response
   try: req_num, object = self._get()
 File "/usr/lib/python2.2/site-packages/rdiff_backup/connection.py",
line 251, in _get
   log.Log.conn("received", result, req_num)
 File "/usr/lib/python2.2/site-packages/rdiff_backup/log.py", line 152, in conn
   (conn_str, direction, req_num, result_repr), 9)
 File "/usr/lib/python2.2/site-packages/rdiff_backup/log.py", line
135, in log_to_term
   termfp.write(self.format(message, self.term_verbosity))
IOError: [Errno 11] Resource temporarily unavailable
Traceback (most recent call last):
 File "/usr/bin/rdiff-backup", line 23, in ?
   rdiff_backup.Main.Main(sys.argv[1:])
 File "/usr/lib/python2.3/site-packages/rdiff_backup/Main.py", line
285, in Main
   take_action(rps)
 File "/usr/lib/python2.3/site-packages/rdiff_backup/Main.py", line
253, in take_action
   connection.PipeConnection(sys.stdin, sys.stdout).Server()
 File "/usr/lib/python2.3/site-packages/rdiff_backup/connection.py",
line 352, in Server
   self.get_response(-1)
 File "/usr/lib/python2.3/site-packages/rdiff_backup/connection.py",
line 314, in get_response
   try: req_num, object = self._get()
 File "/usr/lib/python2.3/site-packages/rdiff_backup/connection.py",
line 230, in _get
   raise ConnectionReadError("Truncated header string (problem "
rdiff_backup.connection.ConnectionReadError: Truncated header string
(problem probably originated remotely)

Mirror is about 12 minutes into the future in respect to primary.
That doesn't matter, does it?

Chris.


On 6/7/06, dean gaudet <address@hidden> wrote:
> On Wed, 7 Jun 2006, Chris Fanning wrote:
>
> > I've got a problem running rdiff-backup as a cron job.
>
> do you get any mail from cron?  usually you should get some mail with
> stdout/stderr in it... i'm guessing it would help diagnose this...