[Box Backup] bbackupctl always fails when run from crontab. Manual always OK?

Tobias Balle-Petersen boxbackup@fluffy.co.uk
Thu, 24 Aug 2006 12:14:00 +0200


Hello...

As expected, the scond time bbackupctl was  run as a cronjob it failed.  
Log from the client  seen below.

I have just performed a "/usr/local/bin/bbackupctl -c 
/etc/box_minimoni/bbackupd.conf reload"
I expect this to allow the next run started from the crontab to be run 
without errors. Will report back.

Thanks,
Tobias

Log from client:
Aug 24 10:00:00 yoiko bbackupd[11514]: Incoming connection from local 
(UNIX socket)
Aug 24 10:00:00 yoiko bbackupd[11514]: Connection from command socket
Aug 24 10:00:00 yoiko bbackupd[11514]: TRACE: Receiving command 'sync' 
over command socket\n
Aug 24 10:00:00 yoiko bbackupd[11514]: TRACE: Receiving command 'quit' 
over command socket\n
Aug 24 10:00:00 yoiko bbackupd[11514]: TRACE: Closing command connection\n
Aug 24 10:00:00 yoiko bbackupd[11514]: Beginning scan of local files
Aug 24 10:00:00 yoiko bbackupd[11514]: Opening connection to server 
minimoni.local.kontrapunkt.dk...
Aug 24 10:00:00 yoiko bbackupd[11514]: TRACE: Send block allocation size 
is 4\n
Aug 24 10:00:01 yoiko bbackupd[11514]: Connection made, login successful
Aug 24 10:00:01 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:00:01 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5420\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2674\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Diff: 3170 new bytes 
found, 22 old blocks used\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
16 old blocks used\n
Aug 24 10:00:05 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:00:09 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:00:13 yoiko bbackupd[11514]: TRACE: Diff: 3629 new bytes 
found, 234 old blocks used\n
Aug 24 10:00:13 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:01:37 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Billund Airport/1773 Oversigtskort og 
P-skilte\n
Aug 24 10:01:37 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Billund Airport/Billund Airport 02.01.06\n
Aug 24 10:01:37 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Billund Airport/untitled folder\n
Aug 24 10:02:38 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2240\n
Aug 24 10:02:38 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2240 to 2985\n
............
Aug 24 10:13:01 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2907 to 5044\n
Aug 24 10:13:02 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:03 yoiko bbackupd[11514]: TRACE: Diff: 1067257 new bytes 
found, 2 old blocks used\n
Aug 24 10:13:03 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:03 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:13:03 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5173\n
Aug 24 10:13:04 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2862\n
Aug 24 10:13:04 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2862 to 5038\n
Aug 24 10:13:05 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:08 yoiko bbackupd[11514]: TRACE: Diff: 1248547 new bytes 
found, 2104 old blocks used\n
Aug 24 10:13:08 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2092 to 4268\n
Aug 24 10:13:09 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2342\n
Aug 24 10:13:09 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2342 to 4418\n
Aug 24 10:13:10 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Diff: 438272 new bytes 
found, 1446 old blocks used\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2870\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2870 to 3834\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3834 to 5741\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:13:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5276\n
Aug 24 10:13:22 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:24 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
1919 old blocks used\n
Aug 24 10:13:24 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:24 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:26 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
1920 old blocks used\n
Aug 24 10:13:26 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:30 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:30 yoiko bbackupd[11514]: TRACE: Diff: 8192 new bytes 
found, 1 old blocks used\n
Aug 24 10:13:30 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:30 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:13:30 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5182\n
Aug 24 10:13:32 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:13:32 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5173\n
Aug 24 10:13:36 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:36 yoiko bbackupd[11514]: TRACE: Diff: 2746 new bytes 
found, 8 old blocks used\n
Aug 24 10:13:36 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:36 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2909\n
.......
Aug 24 10:13:39 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:13:40 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:13:40 yoiko bbackupd[11514]: TRACE: Diff: 4302 new bytes 
found, 6 old blocks used\n
Aug 24 10:13:40 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:13:40 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:13:40 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5292\n
......
Aug 24 10:13:47 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2902 to 5078\n
Aug 24 10:13:51 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:14:13 yoiko bbackupd[11514]: TRACE: Diff: 1419698 new bytes 
found, 952 old blocks used\n
Aug 24 10:14:13 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:14:13 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1085 to 3015\n
..........
Aug 24 10:14:37 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:14:37 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5390\n
Aug 24 10:14:44 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:14:44 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 5 
old blocks used\n
Aug 24 10:14:44 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:01 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:01 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
311 old blocks used\n
Aug 24 10:16:01 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:01 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:02 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
372 old blocks used\n
Aug 24 10:16:02 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
15 old blocks used\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
37 old blocks used\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:16:03 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5155\n
Aug 24 10:16:04 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:07 yoiko bbackupd[11514]: TRACE: Diff: 88 new bytes found, 
815 old blocks used\n
Aug 24 10:16:07 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:08 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:08 yoiko bbackupd[11514]: TRACE: Diff: 1253376 new bytes 
found, 1547 old blocks used\n
Aug 24 10:16:08 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2972\n
Aug 24 10:16:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2972 to 3969\n
Aug 24 10:16:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3969 to 6039\n
Aug 24 10:16:10 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:16:11 yoiko bbackupd[11514]: TRACE: Diff: 309001 new bytes 
found, 40 old blocks used\n
Aug 24 10:16:11 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:16:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2594\n
Aug 24 10:16:11 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2594 to 3255\n
.............
Aug 24 10:16:42 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2889 to 5065\n
Aug 24 10:16:43 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Cyncron/LR skitser\n
Aug 24 10:16:43 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Cyncron/Rentegnet logo\n
Aug 24 10:16:43 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Cyncron/Web og powerpoint\n
Aug 24 10:18:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3013\n
Aug 24 10:18:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3013 to 5129\n
Aug 24 10:18:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:18:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5269\n
Aug 24 10:18:11 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:18:22 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
2223 old blocks used\n
Aug 24 10:18:22 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:18:28 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 10:18:29 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 3388\n
Aug 24 10:18:29 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3388 to 4715\n
Aug 24 10:18:44 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:18:45 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
158 old blocks used\n
Aug 24 10:18:45 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:18:54 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:20:17 yoiko bbackupd[11514]: TRACE: MaximumDiffingTime reached 
- suspending file diff\n
Aug 24 10:20:17 yoiko bbackupd[11514]: TRACE: Diff: 112380809 new bytes 
found, 15 old blocks used\n
Aug 24 10:20:17 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:20:17 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1069 to 2669\n
Aug 24 10:20:17 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2669 to 3497\n
Aug 24 10:20:17 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3497 to 3641\n
Aug 24 10:20:57 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:20:57 yoiko bbackupd[11514]: TRACE: KeepAliveTime reached - 
initiating keep-alive\n
Aug 24 10:20:57 yoiko bbackupd[11514]: TRACE: Diff: 5712 new bytes 
found, 175 old blocks used\n
Aug 24 10:20:57 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:20:57 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2587\n
Aug 24 10:20:58 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:20:59 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
180 old blocks used\n
Aug 24 10:20:59 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 8 
old blocks used\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Diff: 12288 new bytes 
found, 83 old blocks used\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2440\n
Aug 24 10:21:02 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Diff: 286720 new bytes 
found, 2426 old blocks used\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2528\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2528 to 2672\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Diff: 12288 new bytes 
found, 85 old blocks used\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:07 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2591\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Diff: 12288 new bytes 
found, 27 old blocks used\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2381\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Diff: 12288 new bytes 
found, 37 old blocks used\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2559\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Diff: 12288 new bytes 
found, 20 old blocks used\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 10:21:08 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2333\n
Aug 24 10:23:29 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
..........
Aug 24 11:06:37 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2950 to 4406\n
Aug 24 11:07:09 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 11:07:13 yoiko bbackupd[11514]: TRACE: Diff: 117248 new bytes 
found, 3833 old blocks used\n
Aug 24 11:07:13 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 11:07:13 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2617\n
Aug 24 11:07:13 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2617 to 3596\n
Aug 24 11:07:13 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3596 to 5415\n
Aug 24 11:07:30 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 11:07:37 yoiko bbackupd[11514]: TRACE: Diff: 0 new bytes found, 
1440 old blocks used\n
Aug 24 11:07:37 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 11:08:03 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 11:08:04 yoiko bbackupd[11514]: TRACE: Diff: 14336 new bytes 
found, 725 old blocks used\n
Aug 24 11:08:04 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 11:08:04 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 11:08:04 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 4998\n
Aug 24 11:08:04 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 11:08:05 yoiko bbackupd[11514]: TRACE: Diff: 11776 new bytes 
found, 894 old blocks used\n
Aug 24 11:08:05 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 11:08:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 11:08:05 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5133\n
Aug 24 11:11:23 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2849\n
Aug 24 11:11:23 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2849 to 5025\n
Aug 24 11:11:27 yoiko bbackupd[11514]: TRACE: Initiated timer for file 
diff control\n
Aug 24 11:11:27 yoiko bbackupd[11514]: TRACE: Diff: 880838 new bytes 
found, 152 old blocks used\n
Aug 24 11:11:27 yoiko bbackupd[11514]: TRACE: Suspended timer for file 
diff control\n
Aug 24 11:11:27 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2601\n
Aug 24 11:11:27 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 2601 to 4777\n
Aug 24 11:11:27 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 2606\n
...................
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 1068 to 3244\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Reallocating 
EncodingBuffer from 3244 to 5413\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Novo Nordisk/Recruitment/recruitment_ms\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Novo Nordisk/Recruitment/recruitment_tos\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Novo Nordisk/Recruitment/recruitment2_ms\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Novo Nordisk/Recruitment/re.kmo\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Novo Nordisk/Recruitment/recruitment 
transport fredag\n
Aug 24 11:16:19 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Novo Nordisk/Recruitment/re.transp.mandag\n
Aug 24 11:20:28 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/Medicon\n
Aug 24 11:20:28 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/clients/MOUNT\n
Aug 24 11:20:44 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/guests/Bergen Bybane\n
Aug 24 11:20:44 yoiko bbackupd[11514]: TRACE: Deleted directory record 
for /Volumes/raidA/files/guests/Library1\n
Aug 24 11:41:58 yoiko bbackupd[11514]: TRACE: Exception thrown: 
ConnectionException(Conn_TLSReadFailed) at SocketStreamTLS.cpp(361)\n
Aug 24 11:41:58 yoiko bbackupd[11514]: TRACE: Exception thrown: 
ConnectionException(Conn_TLSWriteFailed) at SocketStreamTLS.cpp(426)\n
Aug 24 11:41:59 yoiko bbackupd[11514]: Exception caught (Connection 
TLSReadFailed 7/34), reset state and waiting to retry...
Aug 24 11:42:09 yoiko bbackupd[11514]: File statistics: total file size 
uploaded 13410118455, bytes already on server 341406646, encoded size 
9996343505
Aug 24 11:42:09 yoiko bbackupd[11514]: TRACE: Wait on command socket, 
delay = 1024000000\n
Aug 24 11:59:13 yoiko bbackupd[11514]: TRACE: Wait on command socket, 
delay = 1024000000\n


Tobias Balle-Petersen wrote:
> Hello....
>
> After having restarted bbackupd, bbackupctl has run with succes as a 
> cronjob as seen below (from the server log).
>
> I expect the cronjob to fail on following runs. Will report back.
>
>
> Aug 23 10:00:00 localhost bbstored[3502]: Incoming connection from 
> 192.168.0.51 port 61680 (handling in child 31602)
> Aug 23 10:00:00 localhost bbstored[31602]: Certificate CN: BACKUP-A
> Aug 23 10:00:00 localhost bbstored[31602]: Login: Client ID 0000000A, 
> Read/Write
> Aug 23 10:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 10:19:25 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 11:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 11:19:25 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 12:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 12:19:26 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 13:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 13:19:25 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 14:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 14:19:25 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 15:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 15:19:25 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 16:19:25 localhost bbstored/hk[3503]: Starting housekeeping
> Aug 23 16:19:25 localhost bbstored/hk[3503]: Finished housekeeping
> Aug 23 16:28:01 localhost bbstored[31602]: Session finished
> Aug 23 16:28:01 localhost bbstored[31602]: Connection statistics for 
> BACKUP-A: IN=3473254520 OUT=808297300 TOTAL=4281551820
>
>
> Thanks,
> Tobias
>
>
>
> Tobias Balle-Petersen wrote:
>>
>> I am now running the debug version with extended logging.
>>
>> Actually, I think the 1st time the cronjob (bbackupctl) runs after a 
>> restart of bbackupd, the run completes.
>> Following cronjobs do not. Starting bbackupctl manually does complete.
>>
>> I will let my cronjob run for two days and repport back  with resulat 
>> and logs.
>>
>> Thanks,
>> Tobias
>>
>>
>>
>> Per Thomsen wrote:
>>> On 8/22/06 1:18 PM, tbp wrote:
>>>  
>>>> Hello....
>>>>
>>>> I don't remember ever having seen the filenames of files being backed
>>>> up even with extended logging on.
>>>> Would all files be listed as the are evaluated, or just the new files
>>>> that are to be added to the store?
>>>>     
>>> Only files that are sent to the store. Here's an example of what I'm
>>> seeing with 'ExtendedLogging = yes':
>>>
>>> Aug 20 04:35:07 mostro bbackupd[2125]: Send
>>> StoreFile(0x2f16,0x41b70ed5684c0,0x57a46e0d1741a059,0xce6c,"index.html") 
>>>
>>> Aug 20 04:35:07 mostro bbackupd[2125]: Sending stream, size uncertain
>>> Aug 20 04:35:07 mostro bbackupd[2125]: Receive Success(0xced0)
>>> Aug 20 04:35:07 mostro bbackupd[2125]: Send
>>> StoreFile(0x2f16,0x41b70ed5684c0,0x974c3454965ac769,0x0,"usage.png")
>>> Aug 20 04:35:07 mostro bbackupd[2125]: Sending stream, size uncertain
>>> Aug 20 04:35:07 mostro bbackupd[2125]: Receive Success(0xced1) You 
>>> are not seeing anything like that on the client machine? These logs
>>> are from a 0.09 installation, but I think this should log the same on
>>> 0.10.                 
>>>>> Finally, I just want to make sure you're using the 'KeepAliveTime"
>>>>> parameter in the bbackupd.conf file. You're receiving a timeout 
>>>>> error,
>>>>> so I just want to eliminate the lack of that parameter as the reason.
>>>>>         
>>>> KeepAliveTime is 10
>>>>     
>>> OK. I guess there is no harm in using the 10 second number. I believe
>>> the SSL timeout is 180 seconds, so I set my KeepAliveTime to 90. Don't
>>> know that it would have any impact.
>>>
>>> Thanks,
>>> Per
>>>
>>>   
>>
>> _______________________________________________
>> boxbackup mailing list
>> boxbackup@fluffy.co.uk
>> http://lists.warhead.org.uk/mailman/listinfo/boxbackup
>>
>
> _______________________________________________
> boxbackup mailing list
> boxbackup@fluffy.co.uk
> http://lists.warhead.org.uk/mailman/listinfo/boxbackup
>