On Wednesday, September 22, 2010 06:01:08 am Jean-Louis Martineau did opine:
> Attachment included. > > Jean-Louis > > Jean-Louis Martineau wrote: > > I can't find why it flush nothing. > > > > Can you upgrade to latest SVN and try the attached patch, it add mode > > degugging. > > Add 'debug-driver 1' in amanda.conf > > > > Retry amflush and send me the resulting amflush.1 file. 3.2.0.svn.3434 with patch. taper didn't write, 28Gb in holding disk. Ran amflush -f Daily by hand. Returned in about 2 seconds. amflush.20100922060304.debug: Wed Sep 22 06:03:04 2010: amflush: pid 22495 ruid 501 euid 501 version 3.2.0alpha.svn.3434: start at Wed Sep 22 06:03:04 2010 Wed Sep 22 06:03:04 2010: amflush: pid 22495 ruid 501 euid 501 version 3.2.0alpha.svn.3434: rename at Wed Sep 22 06:03:04 2010 Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._GenesAmandaHelper-0.6.2' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._bin.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._boot.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._etc.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._home.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._lib.2' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._opt.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._root.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._sbin.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._tmp.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_X11R6.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_bin.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_dlds_misc.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_dlds_rpms.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_dlds_tgzs.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_include.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_lib.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_libexec.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_local.2' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_movies.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_music.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_pix.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_sbin.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_share.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._usr_src.2' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/coyote._var.1' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/shop._etc.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/shop._home.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/shop._usr_lib_amanda.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/shop._usr_local.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/shop._usr_src.0' Wed Sep 22 06:03:14 2010: amflush: flushing '/usr/dumps/20100922005007/shop._var_lib_amanda.0' amflush.1: amflush: start at Wed Sep 22 06:03:14 EDT 2010 amflush: datestamp 20100922060304 amflush: starttime 20100922060304 amflush: starttime-locale-independent 2010-09-22 06:03:14 EDT FLUSH coyote /GenesAmandaHelper-0.6 20100922005007 2 /usr/dumps/20100922005007/coyote._GenesAmandaHelper-0.6.2 FLUSH coyote /bin 20100922005007 1 /usr/dumps/20100922005007/coyote._bin.1 FLUSH coyote /boot 20100922005007 1 /usr/dumps/20100922005007/coyote._boot.1 FLUSH coyote /etc 20100922005007 1 /usr/dumps/20100922005007/coyote._etc.1 FLUSH coyote /home 20100922005007 0 /usr/dumps/20100922005007/coyote._home.0 FLUSH coyote /lib 20100922005007 2 /usr/dumps/20100922005007/coyote._lib.2 FLUSH coyote /opt 20100922005007 0 /usr/dumps/20100922005007/coyote._opt.0 FLUSH coyote /root 20100922005007 1 /usr/dumps/20100922005007/coyote._root.1 FLUSH coyote /sbin 20100922005007 1 /usr/dumps/20100922005007/coyote._sbin.1 FLUSH coyote /tmp 20100922005007 1 /usr/dumps/20100922005007/coyote._tmp.1 FLUSH coyote /usr/X11R6 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_X11R6.1 FLUSH coyote /usr/bin 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_bin.1 FLUSH coyote /usr/dlds/misc 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_dlds_misc.1 FLUSH coyote /usr/dlds/rpms 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_dlds_rpms.1 FLUSH coyote /usr/dlds/tgzs 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_dlds_tgzs.1 FLUSH coyote /usr/include 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_include.1 FLUSH coyote /usr/lib 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_lib.1 FLUSH coyote /usr/libexec 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_libexec.1 FLUSH coyote /usr/local 20100922005007 2 /usr/dumps/20100922005007/coyote._usr_local.2 FLUSH coyote /usr/movies 20100922005007 0 /usr/dumps/20100922005007/coyote._usr_movies.0 FLUSH coyote /usr/music 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_music.1 FLUSH coyote /usr/pix 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_pix.1 FLUSH coyote /usr/sbin 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_sbin.1 FLUSH coyote /usr/share 20100922005007 1 /usr/dumps/20100922005007/coyote._usr_share.1 FLUSH coyote /usr/src 20100922005007 2 /usr/dumps/20100922005007/coyote._usr_src.2 FLUSH coyote /var 20100922005007 1 /usr/dumps/20100922005007/coyote._var.1 FLUSH shop /etc 20100922005007 0 /usr/dumps/20100922005007/shop._etc.0 FLUSH shop /home 20100922005007 0 /usr/dumps/20100922005007/shop._home.0 FLUSH shop /usr/lib/amanda 20100922005007 0 /usr/dumps/20100922005007/shop._usr_lib_amanda.0 FLUSH shop /usr/local 20100922005007 0 /usr/dumps/20100922005007/shop._usr_local.0 FLUSH shop /usr/src 20100922005007 0 /usr/dumps/20100922005007/shop._usr_src.0 FLUSH shop /var/lib/amanda 20100922005007 0 /usr/dumps/20100922005007/shop._var_lib_amanda.0 ENDFLUSH driver: pid 22549 executable driver version 3.2.0alpha.svn.3434 driver: tape size 40960000 driver: adding holding disk 0 dir /usr/dumps size 590528512 chunksize 2048000 reserving 0 out of 590528512 for degraded-mode dumps driver: send-cmd time 0.025 to taper: START-TAPER worker0 20100922060304 driver: start time 0.025 inparallel 2 bandwidth 6000 diskspace 590528512 dir OBSOLETE datestamp 20100922060304 driver: drain-ends tapeq LARGESTFIT big-dumpers BTSBTSBTSBTS driver: state time 0.025 free kps: 6000 space: 590528512 taper: idle idle-dumpers: 2 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not- idle driver: interface-state time 0.025 if le0: free 5000 if local: free 1000 driver: hdisk-state time 0.025 hdisk 0: free 590528512 dumpers 0 driver: state time 0.422 free kps: 6000 space: 590528512 taper: idle idle-dumpers: 2 qlen tapeq: 32 runq: 0 roomq: 0 wakeup: 0 driver-idle: not- idle driver: interface-state time 0.422 if le0: free 5000 if local: free 1000 driver: hdisk-state time 0.422 hdisk 0: free 590528512 dumpers 0 driver: result time 0.422 from taper: TAPER-OK worker0 driver: state time 0.422 free kps: 6000 space: 590528512 taper: idle idle-dumpers: 2 qlen tapeq: 32 runq: 0 roomq: 0 wakeup: 0 driver-idle: no- dumpers driver: interface-state time 0.422 if le0: free 5000 if local: free 1000 driver: hdisk-state time 0.422 hdisk 0: free 590528512 dumpers 0 driver: state time 0.422 free kps: 6000 space: 590528512 taper: idle idle-dumpers: 2 qlen tapeq: 32 runq: 0 roomq: 0 wakeup: 0 driver-idle: no- dumpers driver: interface-state time 0.422 if le0: free 5000 if local: free 1000 driver: hdisk-state time 0.422 hdisk 0: free 590528512 dumpers 0 driver: state time 0.422 free kps: 6000 space: 590528512 taper: idle idle-dumpers: 2 qlen tapeq: 32 runq: 0 roomq: 0 wakeup: 0 driver-idle: no- dumpers driver: interface-state time 0.422 if le0: free 5000 if local: free 1000 driver: hdisk-state time 0.422 hdisk 0: free 590528512 dumpers 0 driver: state time 0.422 free kps: 6000 space: 590528512 taper: idle idle-dumpers: 2 qlen tapeq: 32 runq: 0 roomq: 0 wakeup: 0 driver-idle: no- dumpers driver: interface-state time 0.422 if le0: free 5000 if local: free 1000 driver: hdisk-state time 0.422 hdisk 0: free 590528512 dumpers 0 driver: QUITTING time 0.422 telling children to quit driver: send-cmd time 0.422 to taper: QUIT driver: FINISHED time 1.427 /usr/bin/lpr: No file!?! driver.201009220603*debug: Wed Sep 22 06:03:14 2010: driver: pid 22549 ruid 501 euid 501 version 3.2.0alpha.svn.3434: start at Wed Sep 22 06:03:14 2010 Wed Sep 22 06:03:14 2010: driver: pid 22549 ruid 501 euid 501 version 3.2.0alpha.svn.3434: rename at Wed Sep 22 06:03:14 2010 Wed Sep 22 06:03:14 2010: driver: flush-threshold-dumped: 0 Wed Sep 22 06:03:14 2010: driver: flush-threshold-scheduled: 0 Wed Sep 22 06:03:14 2010: driver: taperflush: 0 Wed Sep 22 06:03:14 2010: driver: dumpers_size: 0 Wed Sep 22 06:03:14 2010: driver: runq_size: 0 Wed Sep 22 06:03:14 2010: driver: directq_size: 0 Wed Sep 22 06:03:14 2010: driver: tapeq_size: 29178388 Wed Sep 22 06:03:14 2010: driver: sched_size: 29178388 Wed Sep 22 06:03:14 2010: driver: dump_to_disk_size: 0 Wed Sep 22 06:03:14 2010: driver: nb_taper_active: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_dumped: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_scheduled: 0 Wed Sep 22 06:03:14 2010: driver: my_taperflush: 0 Wed Sep 22 06:03:14 2010: driver: taper state: 1 Wed Sep 22 06:03:14 2010: driver: taper_nb_scan_volume state: 1 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: runq: 1 Wed Sep 22 06:03:14 2010: driver: directq: 1 Wed Sep 22 06:03:14 2010: driver: roomq: 1 Wed Sep 22 06:03:14 2010: driver: idle_reason: 0 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: force_flush: 0 Wed Sep 22 06:03:14 2010: driver: dumpers_size: 0 Wed Sep 22 06:03:14 2010: driver: runq_size: 0 Wed Sep 22 06:03:14 2010: driver: directq_size: 0 Wed Sep 22 06:03:14 2010: driver: tapeq_size: 29178388 Wed Sep 22 06:03:14 2010: driver: sched_size: 29178388 Wed Sep 22 06:03:14 2010: driver: dump_to_disk_size: 0 Wed Sep 22 06:03:14 2010: driver: nb_taper_active: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_dumped: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_scheduled: 0 Wed Sep 22 06:03:14 2010: driver: my_taperflush: 0 Wed Sep 22 06:03:14 2010: driver: taper state: 6 Wed Sep 22 06:03:14 2010: driver: taper_nb_scan_volume state: 0 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: runq: 1 Wed Sep 22 06:03:14 2010: driver: directq: 1 Wed Sep 22 06:03:14 2010: driver: roomq: 1 Wed Sep 22 06:03:14 2010: driver: idle_reason: 1 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: force_flush: 0 Wed Sep 22 06:03:14 2010: driver: dumpers_size: 0 Wed Sep 22 06:03:14 2010: driver: runq_size: 0 Wed Sep 22 06:03:14 2010: driver: directq_size: 0 Wed Sep 22 06:03:14 2010: driver: tapeq_size: 29178388 Wed Sep 22 06:03:14 2010: driver: sched_size: 29178388 Wed Sep 22 06:03:14 2010: driver: dump_to_disk_size: 0 Wed Sep 22 06:03:14 2010: driver: nb_taper_active: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_dumped: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_scheduled: 0 Wed Sep 22 06:03:14 2010: driver: my_taperflush: 0 Wed Sep 22 06:03:14 2010: driver: taper state: 6 Wed Sep 22 06:03:14 2010: driver: taper_nb_scan_volume state: 0 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: runq: 1 Wed Sep 22 06:03:14 2010: driver: directq: 1 Wed Sep 22 06:03:14 2010: driver: roomq: 1 Wed Sep 22 06:03:14 2010: driver: idle_reason: 1 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: force_flush: 0 Wed Sep 22 06:03:14 2010: driver: dumpers_size: 0 Wed Sep 22 06:03:14 2010: driver: runq_size: 0 Wed Sep 22 06:03:14 2010: driver: directq_size: 0 Wed Sep 22 06:03:14 2010: driver: tapeq_size: 29178388 Wed Sep 22 06:03:14 2010: driver: sched_size: 29178388 Wed Sep 22 06:03:14 2010: driver: dump_to_disk_size: 0 Wed Sep 22 06:03:14 2010: driver: nb_taper_active: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_dumped: 0 Wed Sep 22 06:03:14 2010: driver: my_flush_threshold_scheduled: 0 Wed Sep 22 06:03:14 2010: driver: my_taperflush: 0 Wed Sep 22 06:03:14 2010: driver: taper state: 6 Wed Sep 22 06:03:14 2010: driver: taper_nb_scan_volume state: 0 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: runq: 1 Wed Sep 22 06:03:14 2010: driver: directq: 1 Wed Sep 22 06:03:14 2010: driver: roomq: 1 Wed Sep 22 06:03:14 2010: driver: idle_reason: 1 Wed Sep 22 06:03:14 2010: driver: degraded_mode: 0 Wed Sep 22 06:03:14 2010: driver: force_flush: 1 Wed Sep 22 06:03:15 2010: driver: pid 22549 finish time Wed Sep 22 06:03:15 2010 taper.20100922060314.debug: Wed Sep 22 06:03:14 2010: taper: pid 22550 ruid 501 euid 501 version 3.2.0alpha.svn.3434: start at Wed Sep 22 06:03:14 2010 Wed Sep 22 06:03:14 2010: taper: pid 22550 ruid 501 euid 501 version 3.2.0alpha.svn.3434: rename at Wed Sep 22 06:03:14 2010 Wed Sep 22 06:03:14 2010: taper: driver/taper << START-TAPER worker0 20100922060304 Wed Sep 22 06:03:14 2010: taper: Amanda::Taper::Scribe: starting Wed Sep 22 06:03:14 2010: taper: Amanda::Taper::Scan::traditional stage 1: search for oldest reusable volume Wed Sep 22 06:03:14 2010: taper: Amanda::Taper::Scan::traditional oldest reusable volume is 'Dailys-25' Wed Sep 22 06:03:14 2010: taper: Amanda::Taper::Scan::traditional stage 1: searching oldest reusable volume 'Dailys-25' Wed Sep 22 06:03:14 2010: taper: Amanda::Taper::Scan::traditional result: 'Dailys-25' on file:/amandatapes/Dailys/drive0 slot 25, mode 2 Wed Sep 22 06:03:14 2010: taper: driver/taper >> TAPER-OK worker0 Wed Sep 22 06:03:14 2010: taper: driver/taper << QUIT Wed Sep 22 06:03:14 2010: taper: Amanda::Taper::Scribe: quitting Wed Sep 22 06:03:14 2010: taper: pid 22550 finish time Wed Sep 22 06:03:14 2010 And nothing was written, I still have 28Gb in the holding disk. All slots in /amandatapes/Dailys are owned by amanda:disk, and slot25 still contains: [r...@coyote Daily]# ls -l /amandatapes/Dailys/slot25 total 15581292 -rw------- 1 amanda disk 32768 2010-08-23 00:34 00000.Dailys-25 -rw------- 1 amanda disk 52520 2010-08-23 00:34 00001.shop._etc.1 -rw------- 1 amanda disk 32976 2010-08-23 00:34 00002.shop._usr_lib_amanda.1 -rw------- 1 amanda disk 377622528 2010-08-23 00:34 00003.coyote._usr_bin.0 -rw------- 1 amanda disk 8429568 2010-08-23 00:34 00004.coyote._boot.1 -rw------- 1 amanda disk 72333 2010-08-23 00:34 00005.shop._var_lib_amanda.1 -rw------- 1 amanda disk 125922 2010-08-23 00:34 00006.shop._usr_src.1 -rw------- 1 amanda disk 50175 2010-08-23 00:34 00007.shop._home.1 -rw------- 1 amanda disk 127098122 2010-08-23 00:34 00008.coyote._GenesAmandaHelper-0.6.1 -rw------- 1 amanda disk 33546 2010-08-23 00:34 00009.shop._usr_local.1 -rw------- 1 amanda disk 875754810 2010-08-23 00:39 00010.coyote._usr_share.0 -rw------- 1 amanda disk 46147054 2010-08-23 00:39 00011.coyote._tmp.0 -rw------- 1 amanda disk 12741173086 2010-08-23 02:14 00012.coyote._home.0 -rw------- 1 amanda disk 160765624 2010-08-23 02:14 00013.coyote._usr_local.0 -rw------- 1 amanda disk 176128 2010-08-23 02:14 00014.coyote._usr_pix.1 -rw------- 1 amanda disk 1026709167 2010-08-23 02:23 00015.coyote._usr_src.0 -rw------- 1 amanda disk 360417641 2010-08-23 02:27 00016.coyote._lib.0 -rw------- 1 amanda disk 24295849 2010-08-23 02:27 00017.coyote._var.3 -rw------- 1 amanda disk 32969 2010-08-23 02:27 00018.coyote._usr_X11R6.1 -rw------- 1 amanda disk 32973 2010-08-23 02:28 00019.coyote._usr_libexec.1 -rw------- 1 amanda disk 1422928 2010-08-23 02:28 00020.coyote._usr_lib.2 -rw------- 1 amanda disk 103875 2010-08-23 02:28 00021.coyote._root.1 -rw------- 1 amanda disk 70002 2010-08-23 02:28 00022.coyote._etc.1 -rw------- 1 amanda disk 73728 2010-08-23 02:29 00023.coyote._usr_movies.1 -rw------- 1 amanda disk 73728 2010-08-23 02:29 00024.coyote._usr_dlds_misc.1 -rw------- 1 amanda disk 396390 2010-08-23 02:29 00025.coyote._opt.1 -rw------- 1 amanda disk 50350 2010-08-23 02:29 00026.coyote._usr_include.1 -rw------- 1 amanda disk 43008 2010-08-23 02:30 00027.coyote._usr_music.1 -rw------- 1 amanda disk 43008 2010-08-23 02:30 00028.coyote._usr_dlds_tgzs.1 -rw------- 1 amanda disk 43008 2010-08-23 02:30 00029.coyote._usr_dlds_rpms.1 -rw------- 1 amanda disk 43008 2010-08-23 02:30 00030.coyote._sbin.1 -rw------- 1 amanda disk 43008 2010-08-23 02:31 00031.coyote._bin.1 -rw------- 1 amanda disk 35304 2010-08-23 02:31 00032.coyote._usr_sbin.1 -rw-r--r-- 1 amanda disk 102400 2010-08-23 02:32 configuration.tar -rw-r--r-- 1 amanda disk 187893760 2010-08-23 02:32 indices.tar Next? Thank you. -- Cheers, Gene "There are four boxes to be used in defense of liberty: soap, ballot, jury, and ammo. Please use in that order." -Ed Howdershelt (Author) Any sufficiently advanced technology is indistinguishable from a rigged demo.
