On Friday 02 November 2018 08:32:21 Nathan Stratton Treadway wrote:
> On Thu, Nov 01, 2018 at 11:05:37 -0400, Gene Heskett wrote:
> > This may be a disaster, but I've put 3.5.1 back in, but I've rebuilt
>
> Okay, am I understanding correctly that you now have two DLEs left
> that are affected by this "info on small DLEs not saved" bug,
> shop:/usr/local
> and
> lathe:/usr/local
> ?
>
No, I haven't removed them from the disklist, so all are yet there.
> Also, did your run last night generate a
> .../server/Dailys/driver.DATETIMESTAMP.debug file?
>
> (On my system, the file was called
> /var/log/amanda/server/TestBackup/driver.20181102014004.debug
> ; not sure what path your system would use. )
>
> If so, please run "grep -C5 "not updating"
> <PATH-TO-DRIVER-DEBUG-FILE>" on the debug log generated by last
> night's run, and sent the resulting output. That will help confirm my
> hypothesis as to where the problem is originating in the Amanda code.
>
Nathan
word wrap off
root@coyote:/tmp/amanda-dbg/server# grep -C5 "not updating"
driver.20181102*.debug
Fri Nov 02 03:02:28.957068486 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 83.881 from dumper2: DONE 02-00022 10 1 0 00000000:0
00000000:0 "[sec 0.038 kb 1 kps 25.8 orig-kb 10]"
Fri Nov 02 03:02:28.961606919 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 83.886 from chunker2: DUMPER-STATUS 02-00022
Fri Nov 02 03:02:28.961649485 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 83.886 to chunker2: DONE 02-00022 00000000:0
Fri Nov 02 03:02:28.962357047 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 83.886 from chunker2: DONE 02-00022 0 "81c7e0c3:183" "[sec 1.119998
kb 0 kps 0.000000]"
Fri Nov 02 03:02:28.962388529 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 83.886 to chunker2: QUIT
Fri Nov 02 03:02:28.962408287 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:28.962574394 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='GO704' disk='/var/amanda'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:28.962732864 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='GO704' disk='/var/amanda'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:28.969540117 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 83.893 to chunker2: START 20181102030105
Fri Nov 02 03:02:28.969652991 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 83.894 to chunker2: SHM-WRITE
02-00024 /usr/dumps/20181102030105/GO704._usr_local.1 GO704
ffffffff9efefbffffffffff1f000000000000 /usr/local 1 20181102030105 2048000
GNUTAR
128
|;auth=bsdtcp;compress-best;index;exclude-list=/GenesAmandaHelper-0.61/excludes;
Fri Nov 02 03:02:28.969783740 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 83.894 to taper0: FILE-WRITE worker0-0
05-00025 /usr/dumps/20181102030105/GO704._var_amanda.0 GO704 /var/amanda 0
20181102030105 "" "" "" 1 "" "" "" "" 10
--
Fri Nov 02 03:02:31.883381361 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 86.807 from chunker3: DUMPER-STATUS 03-00026
Fri Nov 02 03:02:32.896852625 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 87.821 from dumper3: DONE 03-00026 10 1 1 00000000:0
00000000:0 "[sec 1.101 kb 1 kps 0.9 orig-kb 10]"
Fri Nov 02 03:02:32.896931335 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 87.821 to chunker3: DONE 03-00026 00000000:0
Fri Nov 02 03:02:32.897834905 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 87.822 from chunker3: DONE 03-00026 0 "8463293f:276" "[sec 2.242971
kb 0 kps 0.000000]"
Fri Nov 02 03:02:32.897869725 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 87.822 to chunker3: QUIT
Fri Nov 02 03:02:32.897890829 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:32.898073461 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='shop' disk='/usr/lib/amanda'
dumplevel=1 and blocksize=0
Fri Nov 02 03:02:32.898220363 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='shop' disk='/usr/lib/amanda'
dumplevel=1 and blocksize=0
Fri Nov 02 03:02:32.905092148 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 87.829 to chunker3: START 20181102030105
Fri Nov 02 03:02:32.905210591 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 87.829 to chunker3: SHM-WRITE
03-00030 /usr/dumps/20181102030105/shop._var_amanda.0 shop
ffffffff9efefbffffffffff3f000000000000 /var/amanda 0 20181102030105 2048000
GNUTAR
96
|;auth=bsdtcp;compress-best;index;exclude-list=/GenesAmandaHelper-0.61/excludes;
Fri Nov 02 03:02:32.905333521 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 87.829 to taper0: FILE-WRITE worker0-0
05-00031 /usr/dumps/20181102030105/shop._usr_lib_amanda.1 shop /usr/lib/amanda
1 20181102030105 "" "" "" 1 "" "" "" "" 10
--
Fri Nov 02 03:02:35.356882591 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 90.281 from dumper3: DONE 03-00030 10 1 0 00000000:0
00000000:0 "[sec 0.083 kb 1 kps 12.0 orig-kb 10]"
Fri Nov 02 03:02:35.359502087 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 90.283 from chunker3: DUMPER-STATUS 03-00030
Fri Nov 02 03:02:35.359543740 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 90.283 to chunker3: DONE 03-00030 00000000:0
Fri Nov 02 03:02:35.360279147 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 90.284 from chunker3: DONE 03-00030 0 "993d8203:169" "[sec 2.172337
kb 0 kps 0.000000]"
Fri Nov 02 03:02:35.360314289 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 90.284 to chunker3: QUIT
Fri Nov 02 03:02:35.360336731 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:35.360520918 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='shop' disk='/var/amanda'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:35.360681556 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='shop' disk='/var/amanda'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:35.367536455 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 90.291 to chunker3: START 20181102030105
Fri Nov 02 03:02:35.367665743 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 90.292 to chunker3: SHM-WRITE
03-00034 /usr/dumps/20181102030105/shop._usr_local.0 shop
ffffffff9efefbffffffffff3f000000000000 /usr/local 0 20181102030105 2048000
GNUTAR
96
|;auth=bsdtcp;compress-best;index;exclude-list=/GenesAmandaHelper-0.61/excludes;
Fri Nov 02 03:02:35.367789842 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 90.292 to taper0: FILE-WRITE worker0-0
05-00035 /usr/dumps/20181102030105/shop._var_amanda.0 shop /var/amanda 0
20181102030105 "" "" "" 1 "" "" "" "" 10
--
Fri Nov 02 03:02:38.281804282 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 93.206 from chunker2: DUMPER-STATUS 02-00038
Fri Nov 02 03:02:38.828798438 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 93.753 from dumper3: DONE 03-00034 40 1 1 00000000:0
00000000:0 "[sec 1.110 kb 1 kps 0.9 orig-kb 40]"
Fri Nov 02 03:02:38.828871322 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 93.753 to chunker3: DONE 03-00034 00000000:0
Fri Nov 02 03:02:38.829811806 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 93.754 from chunker3: DONE 03-00034 0 "9e037658:919" "[sec 2.182133
kb 0 kps 0.000000]"
Fri Nov 02 03:02:38.829873224 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 93.754 to chunker3: QUIT
Fri Nov 02 03:02:38.829898026 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:38.830088123 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='shop' disk='/usr/local'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:38.830255499 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='shop' disk='/usr/local'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:38.836506420 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 93.760 to chunker3: START 20181102030105
Fri Nov 02 03:02:38.836626604 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 93.761 to chunker3: SHM-WRITE
03-00042 /usr/dumps/20181102030105/shop._var_lib_amanda.0 shop
ffffffff9efefbffffffffff3f000000000000 /var/lib/amanda 0 20181102030105 2048000
GNUTAR
256
|;auth=bsdtcp;compress-best;index;exclude-list=/GenesAmandaHelper-0.61/excludes;
Fri Nov 02 03:02:38.836753698 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 93.761 to taper0: FILE-WRITE worker0-0
05-00043 /usr/dumps/20181102030105/shop._usr_local.0 shop /usr/local 0
20181102030105 "" "" "" 1 "" "" "" "" 40
--
Fri Nov 02 03:02:40.155397774 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 95.079 from chunker4: DUMPER-STATUS 04-00040
Fri Nov 02 03:02:41.172842852 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 96.097 from dumper4: DONE 04-00040 10 1 1 00000000:0
00000000:0 "[sec 1.103 kb 1 kps 0.9 orig-kb 10]"
Fri Nov 02 03:02:41.172912902 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 96.097 to chunker4: DONE 04-00040 00000000:0
Fri Nov 02 03:02:41.173791715 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 96.098 from chunker4: DONE 04-00040 0 "5e1afe6b:167" "[sec 2.171545
kb 0 kps 0.000000]"
Fri Nov 02 03:02:41.173822218 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 96.098 to chunker4: QUIT
Fri Nov 02 03:02:41.173846575 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:41.174036863 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='lathe' disk='/var/amanda'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:41.174195070 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='lathe' disk='/var/amanda'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:41.180524035 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 96.104 to chunker4: START 20181102030105
Fri Nov 02 03:02:41.180639685 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 96.105 to chunker4: SHM-WRITE
04-00044 /usr/dumps/20181102030105/lathe._usr_local.0 lathe
ffffffff9efefbffffffffff3f000000000000 /usr/local 0 20181102030105 2048000
GNUTAR
96
|;auth=bsdtcp;compress-best;index;exclude-list=/GenesAmandaHelper-0.61/excludes;
Fri Nov 02 03:02:41.180786966 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 96.105 to taper0: FILE-WRITE worker0-0
05-00045 /usr/dumps/20181102030105/lathe._var_amanda.0 lathe /var/amanda 0
20181102030105 "" "" "" 1 "" "" "" "" 10
--
Fri Nov 02 03:02:43.666305830 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 98.590 to dumper2: SHM-DUMP 02-00047 52124 NULL 1 GO704
ffffffff9efefbffffffffff1f000000000000 /usr/lib/amanda NODEVICE 1
2018:10:31:7:1:4 GNUTAR "" "" "" "" "" "" "" 1 "" "" bsdtcp
AMANDA /amanda_shm_control-13636-0 20 |" <auth>bsdtcp</auth>\n
<compress>BEST</compress>\n <record>YES</record>\n <index>YES</index>\n
<datapath>AMANDA</datapath>\n <exclude>\n
<list>/GenesAmandaHelper-0.61/excludes</list>\n </exclude>\n"""
Fri Nov 02 03:02:44.652840345 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 99.577 from dumper4: DONE 04-00044 40 1 1 00000000:0
00000000:0 "[sec 1.114 kb 1 kps 0.9 orig-kb 40]"
Fri Nov 02 03:02:44.652912780 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 99.577 to chunker4: DONE 04-00044 00000000:0
Fri Nov 02 03:02:44.653805740 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 99.578 from chunker4: DONE 04-00044 0 "8beb6939:917" "[sec 2.183003
kb 0 kps 0.000000]"
Fri Nov 02 03:02:44.653838103 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 99.578 to chunker4: QUIT
Fri Nov 02 03:02:44.653861950 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:44.654051556 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='lathe' disk='/usr/local'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:44.654198490 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='lathe' disk='/usr/local'
dumplevel=0 and blocksize=0
Fri Nov 02 03:02:44.660903231 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 99.585 to chunker3: START 20181102030105
Fri Nov 02 03:02:44.661020505 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 99.585 to chunker3: SHM-WRITE
03-00049 /usr/dumps/20181102030105/lathe._usr_lib_amanda.1 lathe
ffffffff9efefbffffffffff3f000000000000 /usr/lib/amanda 1 20181102030105 2048000
GNUTAR
96
|;auth=bsdtcp;compress-best;index;exclude-list=/GenesAmandaHelper-0.61/excludes;
Fri Nov 02 03:02:44.661176128 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 99.585 to taper0: FILE-WRITE worker0-0
04-00050 /usr/dumps/20181102030105/lathe._usr_local.0 lathe /usr/local 0
20181102030105 "" "" "" 1 "" "" "" "" 40
--
Fri Nov 02 03:02:47.115415248 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 102.039 from chunker3: DUMPER-STATUS 03-00049
Fri Nov 02 03:02:48.132821906 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 103.057 from dumper3: DONE 03-00049 10 1 1 00000000:0
00000000:0 "[sec 1.103 kb 1 kps 0.9 orig-kb 10]"
Fri Nov 02 03:02:48.132916474 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 103.057 to chunker3: DONE 03-00049 00000000:0
Fri Nov 02 03:02:48.133868020 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 103.058 from chunker3: DONE 03-00049 0 "be35f6cf:275" "[sec
2.172358
kb 0 kps 0.000000]"
Fri Nov 02 03:02:48.133911260 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 103.058 to chunker3: QUIT
Fri Nov 02 03:02:48.133935342 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:02:48.134121444 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='lathe' disk='/usr/lib/amanda'
dumplevel=1 and blocksize=0
Fri Nov 02 03:02:48.134269591 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='lathe' disk='/usr/lib/amanda'
dumplevel=1 and blocksize=0
Fri Nov 02 03:02:48.140244144 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 103.064 to taper0: FILE-WRITE worker0-0
02-00052 /usr/dumps/20181102030105/lathe._usr_lib_amanda.1 lathe
/usr/lib/amanda 1 20181102030105 "" "" "" 1 "" "" "" "" 10
Fri Nov 02 03:02:48.142925445 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 103.067 from taper0: READY worker0-0 02-00052
Fri Nov 02 03:02:48.145216327 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 103.069 from taper0: PARTDONE worker0-0 02-00052 Dailys-29 25
0 "[sec 0.000432 bytes 275 kps 0.000000 orig-kb 10]"
--
Fri Nov 02 03:42:05.046506329 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 2459.970 from chunker0: DUMPER-STATUS 00-00128
Fri Nov 02 03:42:06.084877799 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 2461.009 from dumper0: DONE 00-00128 10 1 1 0869ff35:10240
00847498:146 "[sec 1.099 kb 1 kps 0.9 orig-kb 10]"
Fri Nov 02 03:42:06.084948530 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 2461.009 to chunker0: DONE 00-00128 00847498:146
Fri Nov 02 03:42:06.085891936 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 2461.010 from chunker0: DONE 00-00128 0 "00847498:146" "[sec
1.145017 kb 0 kps 0.000000]"
Fri Nov 02 03:42:06.085943837 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 2461.010 to chunker0: QUIT
Fri Nov 02 03:42:06.085962877 2018: pid 13139: thd-0x9824e00: driver: not
updating because origsize or dumpsize is 0
Fri Nov 02 03:42:06.086158866 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='coyote' disk='/usr/games'
dumplevel=1 and blocksize=0
Fri Nov 02 03:42:06.086317001 2018: pid 13139: thd-0x9824e00: driver: Building
type FILE header of 32768-32768 bytes with name='coyote' disk='/usr/games'
dumplevel=1 and blocksize=0
Fri Nov 02 03:42:06.092807051 2018: pid 13139: thd-0x9824e00: driver: driver:
send-cmd time 2461.017 to taper0: FILE-WRITE worker0-0
00-00130 /usr/dumps/20181102030105/coyote._usr_games.1 coyote /usr/games 1
20181102030105 "" "" "" 1 "" "" "" "" 10
Fri Nov 02 03:42:06.095834444 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 2461.020 from taper0: READY worker0-0 00-00130
Fri Nov 02 03:42:06.098380991 2018: pid 13139: thd-0x9824e00: driver: driver:
result time 2461.022 from taper0: PARTDONE worker0-0 00-00130 Dailys-29 65
0 "[sec 0.000403 bytes 146 kps 0.000000 orig-kb 10]"
I get the impression that "driver" is being lied to by something else?
A du -h on this last stanza's target, "coyote /usr/games", returns 144k.
A dumplevel of 1 might as well be infinite as there are zero changes
since the level 0 unless patience keeps score in that tree. But from
an ls -lR, note file dates:
root@coyote:/usr/games# ls -lR
.:
total 140
-rwxr-xr-x 1 root root 1563 Jan 16 2012 espdiff
-rwxr-xr-x 1 root root 18816 Oct 1 2009 fortune
-rwxr-xr-x 1 root root 117440 Oct 20 2012 kshisen
Not that much of a "gamer" ...
> ----------------------------------------------------------------------
>------ Nathan Stratton Treadway - [email protected] - Mid-Atlantic
> region Ray Ontko & Co. - Software consulting services -
> http://www.ontko.com/ GPG Key:
> http://www.ontko.com/~nathanst/gpg_key.txt ID: 1023D/ECFB6239 Key
> fingerprint = 6AD8 485E 20B9 5C71 231C 0C32 15F3 ADCD ECFB 6239
Copyright 2018 by Maurice E. Heskett
--
Cheers, Gene Heskett
--
"There are four boxes to be used in defense of liberty:
soap, ballot, jury, and ammo. Please use in that order."
-Ed Howdershelt (Author)
Genes Web page <http://geneslinuxbox.net:6309/gene>
