[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
>