git.gag.com Git - debian/amanda/blob - installcheck/amstatus.pl

   1 # Copyright (c) 2008,2009 Zmanda, Inc.  All Rights Reserved.
   2 #
   3 # This program is free software; you can redistribute it and/or modify it
   4 # under the terms of the GNU General Public License version 2 as published
   5 # by the Free Software Foundation.
   6 #
   7 # This program is distributed in the hope that it will be useful, but
   8 # WITHOUT ANY WARRANTY; without even the implied warranty of MERCHANTABILITY
   9 # or FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
  10 # for more details.
  11 #
  12 # You should have received a copy of the GNU General Public License along
  13 # with this program; if not, write to the Free Software Foundation, Inc.,
  14 # 59 Temple Place, Suite 330, Boston, MA  02111-1307 USA
  15 #
  16 # Contact information: Zmanda Inc, 465 S. Mathilda Ave., Suite 300
  17 # Sunnyvale, CA 94086, USA, or: http://www.zmanda.com
  18
  19 use Test::More tests => 10;
  20
  21 use lib "@amperldir@";
  22 use Installcheck;
  23 use Installcheck::Run qw( run run_get );
  24 use Amanda::Paths;
  25 use Amanda::Constants;
  26
  27 my $filename="$Installcheck::TMP/installcheck-amdump.1";
  28 my $testconf = Installcheck::Run::setup();
  29 $testconf->write();
  30
  31 # read __DATA__ to a hash, keyed by the names following '%%%%'
  32 my %logfiles;
  33 my $key = undef;
  34 while (<DATA>) {
  35     if (/^%%%% (.*)/) {
  36         $key = $1;
  37     } else {
  38         $logfiles{$key} .= $_;
  39     }
  40 }
  41
  42 sub write_logfile {
  43     my ($data) = @_;
  44     open(my $fh, ">", $filename) or die("Could not open '$filename' for writing");
  45     print $fh $data;
  46     close($fh);
  47 };
  48
  49 ## try a few various options with a pretty normal logfile
  50
  51 write_logfile($logfiles{'normal'});
  52
  53 ok(run('amstatus', 'TESTCONF', '--file', $filename),
  54     "plain amstatus runs without error");
  55 like($Installcheck::Run::stdout,
  56     qr{clienthost:/some/dir\s*0\s*100k\s*finished\s*\(13:01:53\)},
  57     "output is reasonable");
  58
  59 ok(run('amstatus', 'TESTCONF', '--file', $filename, '--summary'),
  60     "amstatus --summary runs without error");
  61 unlike($Installcheck::Run::stdout,
  62     qr{clienthost:/some/dir\s*0\s*100k\s*finished\s*\(13:01:53\)},
  63     "output does not contain the finished dump");
  64 like($Installcheck::Run::stdout,
  65     qr{taped\s+:\s+1\s+},
  66     "output contains summary info");
  67
  68 ## now test a file with spaces and other funny characters in filenames
  69
  70 write_logfile($logfiles{'quoted'});
  71
  72 ok(run('amstatus', 'TESTCONF', '--file', $filename),
  73     "amstatus runs without error with quoted disknames");
  74 like($Installcheck::Run::stdout,
  75     # note that amstatus' output is quoted, so backslashes are doubled
  76     qr{clienthost:"C:\\\\Some Dir\\\\"\s*0\s*100k\s*finished\s*\(13:01:53\)},
  77     "output is correct");
  78
  79 ## now test a chunker partial result
  80
  81 write_logfile($logfiles{'chunker_partial'});
  82
  83 ok(!run('amstatus', 'TESTCONF', '--file', $filename),
  84     "amstatus return error with chunker partial");
  85 ok($Installcheck::Run::exit_code == 4,
  86     "correct exit code for chunker partial");
  87 like($Installcheck::Run::stdout,
  88     qr{localhost:/etc 0 backup failed: dumper: \[/usr/sbin/tar returned error\] \(7:49:23\)},
  89     "output is correct");
  90
  91 unlink($filename);
  92
  93 __DATA__
  94 %%%% normal
  95 amdump: start at Wed Jun 18 13:01:47 EDT 2008
  96 amdump: datestamp 20080618
  97 amdump: starttime 20080618130147
  98 amdump: starttime-locale-independent 2008-06-18 13:01:47 EDT
  99 planner: pid 4079 executable /path/to/planner version 9.8.7
 100 READING CONF INFO...
 101 planner: timestamp 20080618130147
 102 planner: time 0.000: startup took 0.000 secs
 103
 104 SENDING FLUSHES...
 105 ENDFLUSH
 106
 107 SETTING UP FOR ESTIMATES...
 108 planner: time 0.000: setting up estimates for clienthost:/some/dir
 109 clienthost:/some/dir overdue 14049 days for level 0
 110 setup_estimate: clienthost:/some/dir: command 0, options: none    last_level -1 next_level0 -14049 level_days 0    getting estimates 0 (-2) -1 (-2) -1 (-2)
 111 planner: time 0.000: setting up estimates took 0.000 secs
 112
 113 GETTING ESTIMATES...
 114 driver: pid 4080 executable /path/to/driver version 9.8.7
 115 driver: adding holding disk 0 dir /holding size 868352 chunksize 1048576
 116 reserving 0 out of 868352 for degraded-mode dumps
 117 driver: send-cmd time 0.015 to taper: START-TAPER 20080618130147
 118 taper: pid 4084 executable taper version 9.8.7
 119 driver: started dumper0 pid 4086
 120 driver: send-cmd time 0.031 to dumper0: START 20080618130147
 121 planner: time 0.050: got partial result for host clienthost disk /some/dir: 0 -> -2K, -1 -> -2K, -1 -> -2K
 122 dumper: pid 4090 executable dumper1 version 9.8.7
 123 driver: started dumper1 pid 4090
 124 driver: send-cmd time 0.046 to dumper1: START 20080618130147
 125 driver: started dumper2 pid 4094
 126 driver: send-cmd time 0.048 to dumper2: START 20080618130147
 127 driver: started dumper3 pid 4095
 128 driver: send-cmd time 0.059 to dumper3: START 20080618130147
 129 driver: start time 0.059 inparallel 4 bandwidth 600 diskspace 868352  dir OBSOLETE datestamp 20080618130147 driver: drain-ends tapeq FIRST big-dumpers sssS
 130 dumper: pid 4094 executable dumper2 version 9.8.7
 131 planner: time 0.088: got partial result for host clienthost disk /some/dir: 0 -> 100K, -1 -> -2K, -1 -> -2K
 132 planner: time 0.091: got result for host clienthost disk /some/dir: 0 -> 100K, -1 -> -2K, -1 -> -2K
 133 planner: time 0.091: getting estimates took 0.090 secs
 134 FAILED QUEUE: empty
 135 DONE QUEUE:
 136   0: clienthost     /some/dir
 137
 138 ANALYZING ESTIMATES...
 139 pondering clienthost:/some/dir... next_level0 -14049 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
 140   curr level 0 nsize 100 csize 100 total size 208 total_lev0 100 balanced-lev0size 50
 141 INITIAL SCHEDULE (size 208):
 142   clienthost /some/dir pri 14050 lev 0 nsize 100 csize 100
 143
 144 DELAYING DUMPS IF NEEDED, total_size 208, tape length 102400 mark 4
 145   delay: Total size now 208.
 146
 147 PROMOTING DUMPS IF NEEDED, total_lev0 100, balanced_size 50...
 148 planner: time 0.091: analysis took 0.000 secs
 149
 150 GENERATING SCHEDULE:
 151 --------
 152 DUMP clienthost ffffffff9ffeffffffff1f /some/dir 20080618130147 14050 0 1970:1:1:0:0:0 100 100 0 1024
 153 --------
 154 dumper: pid 4086 executable dumper0 version 9.8.7
 155 dumper: pid 4095 executable dumper3 version 9.8.7
 156 taper: using label `Conf-001' date `20080618130147'
 157 driver: result time 1.312 from taper: TAPER-OK
 158 driver: state time 1.312 free kps: 600 space: 868352 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
 159 driver: interface-state time 1.312 if default: free 600
 160 driver: hdisk-state time 1.312 hdisk 0: free 868352 dumpers 0
 161 driver: flush size 0
 162 driver: started chunker0 pid 4129
 163 driver: send-cmd time 1.314 to chunker0: START 20080618130147
 164 driver: send-cmd time 1.314 to chunker0: PORT-WRITE 00-00001 /holding/20080618130147/clienthost._some_dir.0 clienthost ffffffff9ffeffffffff1f /some/dir 0 1970:1:1:0:0:0 1048576 GNUTAR 192 |;auth=local;index;
 165 chunker: pid 4129 executable chunker0 version 9.8.7
 166 driver: result time 1.330 from chunker0: PORT 1487
 167 driver: send-cmd time 1.330 to dumper0: PORT-DUMP 00-00001 1487 clienthost ffffffff9ffeffffffff1f /some/dir NODEVICE 0 1970:1:1:0:0:0 GNUTAR X amanda X local |"  <auth>local</auth>\n  <record>YES</record>\n  <index>YES</index>\n"
 168 driver: state time 6.408 free kps: 0 space: 868160 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 169 driver: interface-state time 6.408 if default: free 0
 170 driver: hdisk-state time 6.408 hdisk 0: free 868160 dumpers 1
 171 driver: result time 6.408 from dumper0: DONE 00-00001 100 100 0 "[sec 0.012 kb 100 kps 7915.1 orig-kb 100]"
 172 driver: finished-cmd time 6.408 dumper0 dumped clienthost:/some/dir
 173 driver: send-cmd time 6.408 to chunker0: DONE 00-00001
 174 driver: state time 6.408 free kps: 0 space: 868160 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 175 driver: interface-state time 6.408 if default: free 0
 176 driver: hdisk-state time 6.408 hdisk 0: free 868160 dumpers 1
 177 driver: result time 6.408 from chunker0: DONE 00-00001 100 "[sec 5.075 kb 100 kps 26.0]"
 178 driver: finished-cmd time 6.408 chunker0 chunked clienthost:/some/dir
 179 driver: send-cmd time 6.410 to taper: FILE-WRITE 00-00002 /holding/20080618130147/clienthost._some_dir.0 clienthost /some/dir 0 20080618130147 0
 180 driver: startaflush: FIRST clienthost /some/dir 132 102400
 181 driver: state time 6.410 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 182 driver: interface-state time 6.410 if default: free 600
 183 driver: hdisk-state time 6.410 hdisk 0: free 868220 dumpers 0
 184 driver: result time 6.411 from taper: REQUEST-NEW-TAPE 00-00002
 185 driver: send-cmd time 6.411 to taper: NEW-TAPE
 186 driver: state time 6.412 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 187 driver: interface-state time 6.412 if default: free 600
 188 driver: hdisk-state time 6.412 hdisk 0: free 868220 dumpers 0
 189 driver: result time 6.412 from taper: NEW-TAPE 00-00002 Conf-001
 190 driver: state time 6.414 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 191 driver: interface-state time 6.414 if default: free 600
 192 driver: hdisk-state time 6.414 hdisk 0: free 868220 dumpers 0
 193 driver: result time 6.415 from taper: PARTDONE 00-00002 Conf-001 1 100 "[sec 0.001177 kb 100 kps 84961.767205]"
 194 driver: state time 6.415 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 195 driver: interface-state time 6.415 if default: free 600
 196 driver: hdisk-state time 6.415 hdisk 0: free 868220 dumpers 0
 197 driver: result time 6.415 from taper: DONE 00-00002 INPUT-GOOD TAPE-GOOD "[sec 0.001177 kb 100 kps 84961.767205]" "" ""
 198 driver: finished-cmd time 6.415 taper wrote clienthost:/some/dir
 199 driver: state time 6.415 free kps: 600 space: 868352 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 200 driver: interface-state time 6.415 if default: free 600
 201 driver: hdisk-state time 6.415 hdisk 0: free 868352 dumpers 0
 202 driver: QUITTING time 6.415 telling children to quit
 203 driver: send-cmd time 6.415 to dumper0: QUIT
 204 driver: send-cmd time 6.415 to dumper1: QUIT
 205 driver: send-cmd time 6.417 to dumper2: QUIT
 206 driver: send-cmd time 6.417 to dumper3: QUIT
 207 driver: send-cmd time 6.418 to taper: QUIT
 208 taper: DONE
 209 driver: FINISHED time 7.426
 210 amdump: end at Wed Jun 18 13:01:55 EDT 2008
 211 %%%% quoted
 212 amdump: start at Wed Jun 18 13:01:47 EDT 2008
 213 amdump: datestamp 20080618
 214 amdump: starttime 20080618130147
 215 amdump: starttime-locale-independent 2008-06-18 13:01:47 EDT
 216 planner: pid 4079 executable /path/to/planner version 9.8.7
 217 READING CONF INFO...
 218 planner: timestamp 20080618130147
 219 planner: time 0.000: startup took 0.000 secs
 220
 221 SENDING FLUSHES...
 222 ENDFLUSH
 223
 224 SETTING UP FOR ESTIMATES...
 225 planner: time 0.000: setting up estimates for clienthost:"C:\\Some Dir\\"
 226 clienthost:"C:\\Some Dir\\" overdue 14049 days for level 0
 227 setup_estimate: clienthost:"C:\\Some Dir\\": command 0, options: none    last_level -1 next_level0 -14049 level_days 0    getting estimates 0 (-2) -1 (-2) -1 (-2)
 228 planner: time 0.000: setting up estimates took 0.000 secs
 229
 230 GETTING ESTIMATES...
 231 driver: pid 4080 executable /path/to/driver version 9.8.7
 232 driver: adding holding disk 0 dir /holding size 868352 chunksize 1048576
 233 reserving 0 out of 868352 for degraded-mode dumps
 234 driver: send-cmd time 0.015 to taper: START-TAPER 20080618130147
 235 taper: pid 4084 executable taper version 9.8.7
 236 driver: started dumper0 pid 4086
 237 driver: send-cmd time 0.031 to dumper0: START 20080618130147
 238 planner: time 0.050: got partial result for host clienthost disk "C:\\Some Dir\\": 0 -> -2K, -1 -> -2K, -1 -> -2K
 239 dumper: pid 4090 executable dumper1 version 9.8.7
 240 driver: started dumper1 pid 4090
 241 driver: send-cmd time 0.046 to dumper1: START 20080618130147
 242 driver: started dumper2 pid 4094
 243 driver: send-cmd time 0.048 to dumper2: START 20080618130147
 244 driver: started dumper3 pid 4095
 245 driver: send-cmd time 0.059 to dumper3: START 20080618130147
 246 driver: start time 0.059 inparallel 4 bandwidth 600 diskspace 868352  dir OBSOLETE datestamp 20080618130147 driver: drain-ends tapeq FIRST big-dumpers sssS
 247 dumper: pid 4094 executable dumper2 version 9.8.7
 248 planner: time 0.088: got partial result for host clienthost disk "C:\\Some Dir\\": 0 -> 100K, -1 -> -2K, -1 -> -2K
 249 planner: time 0.091: got result for host clienthost disk "C:\\Some Dir\\": 0 -> 100K, -1 -> -2K, -1 -> -2K
 250 planner: time 0.091: getting estimates took 0.090 secs
 251 FAILED QUEUE: empty
 252 DONE QUEUE:
 253   0: clienthost     "C:\\Some Dir\\"
 254
 255 ANALYZING ESTIMATES...
 256 pondering clienthost:"C:\\Some Dir\\"... next_level0 -14049 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
 257   curr level 0 nsize 100 csize 100 total size 208 total_lev0 100 balanced-lev0size 50
 258 INITIAL SCHEDULE (size 208):
 259   clienthost "C:\\Some Dir\\" pri 14050 lev 0 nsize 100 csize 100
 260
 261 DELAYING DUMPS IF NEEDED, total_size 208, tape length 102400 mark 4
 262   delay: Total size now 208.
 263
 264 PROMOTING DUMPS IF NEEDED, total_lev0 100, balanced_size 50...
 265 planner: time 0.091: analysis took 0.000 secs
 266
 267 GENERATING SCHEDULE:
 268 --------
 269 DUMP clienthost ffffffff9ffeffffffff1f "C:\\Some Dir\\" 20080618130147 14050 0 1970:1:1:0:0:0 100 100 0 1024
 270 --------
 271 dumper: pid 4086 executable dumper0 version 9.8.7
 272 dumper: pid 4095 executable dumper3 version 9.8.7
 273 taper: using label `Conf-001' date `20080618130147'
 274 driver: result time 1.312 from taper: TAPER-OK
 275 driver: state time 1.312 free kps: 600 space: 868352 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
 276 driver: interface-state time 1.312 if default: free 600
 277 driver: hdisk-state time 1.312 hdisk 0: free 868352 dumpers 0
 278 driver: flush size 0
 279 driver: started chunker0 pid 4129
 280 driver: send-cmd time 1.314 to chunker0: START 20080618130147
 281 driver: send-cmd time 1.314 to chunker0: PORT-WRITE 00-00001 /holding/20080618130147/clienthost._some_dir.0 clienthost ffffffff9ffeffffffff1f "C:\\Some Dir\\" 0 1970:1:1:0:0:0 1048576 GNUTAR 192 |;auth=local;index;
 282 chunker: pid 4129 executable chunker0 version 9.8.7
 283 driver: result time 1.330 from chunker0: PORT 1487
 284 driver: send-cmd time 1.330 to dumper0: PORT-DUMP 00-00001 1487 clienthost ffffffff9ffeffffffff1f "C:\\Some Dir\\" NODEVICE 0 1970:1:1:0:0:0 GNUTAR X amanda X local |"  <auth>local</auth>\n  <record>YES</record>\n  <index>YES</index>\n"
 285 driver: state time 6.408 free kps: 0 space: 868160 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 286 driver: interface-state time 6.408 if default: free 0
 287 driver: hdisk-state time 6.408 hdisk 0: free 868160 dumpers 1
 288 driver: result time 6.408 from dumper0: DONE 00-00001 100 100 0 "[sec 0.012 kb 100 kps 7915.1 orig-kb 100]"
 289 driver: finished-cmd time 6.408 dumper0 dumped clienthost:"C:\\Some Dir\\"
 290 driver: send-cmd time 6.408 to chunker0: DONE 00-00001
 291 driver: state time 6.408 free kps: 0 space: 868160 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 292 driver: interface-state time 6.408 if default: free 0
 293 driver: hdisk-state time 6.408 hdisk 0: free 868160 dumpers 1
 294 driver: result time 6.408 from chunker0: DONE 00-00001 100 "[sec 5.075 kb 100 kps 26.0]"
 295 driver: finished-cmd time 6.408 chunker0 chunked clienthost:"C:\\Some Dir\\"
 296 driver: send-cmd time 6.410 to taper: FILE-WRITE 00-00002 /holding/20080618130147/clienthost._some_dir.0 clienthost "C:\\Some Dir\\" 0 20080618130147 0
 297 driver: startaflush: FIRST clienthost "C:\\Some Dir\\" 132 102400
 298 driver: state time 6.410 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 299 driver: interface-state time 6.410 if default: free 600
 300 driver: hdisk-state time 6.410 hdisk 0: free 868220 dumpers 0
 301 driver: result time 6.411 from taper: REQUEST-NEW-TAPE 00-00002
 302 driver: send-cmd time 6.411 to taper: NEW-TAPE
 303 driver: state time 6.412 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 304 driver: interface-state time 6.412 if default: free 600
 305 driver: hdisk-state time 6.412 hdisk 0: free 868220 dumpers 0
 306 driver: result time 6.412 from taper: NEW-TAPE 00-00002 Conf-001
 307 driver: state time 6.414 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 308 driver: interface-state time 6.414 if default: free 600
 309 driver: hdisk-state time 6.414 hdisk 0: free 868220 dumpers 0
 310 driver: result time 6.415 from taper: PARTDONE 00-00002 Conf-001 1 100 "[sec 0.001177 kb 100 kps 84961.767205]"
 311 driver: state time 6.415 free kps: 600 space: 868220 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 312 driver: interface-state time 6.415 if default: free 600
 313 driver: hdisk-state time 6.415 hdisk 0: free 868220 dumpers 0
 314 driver: result time 6.415 from taper: DONE 00-00002 INPUT-GOOD TAPE-GOOD "[sec 0.001177 kb 100 kps 84961.767205]" "" ""
 315 driver: finished-cmd time 6.415 taper wrote clienthost:"C:\\Some Dir\\"
 316 driver: state time 6.415 free kps: 600 space: 868352 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 317 driver: interface-state time 6.415 if default: free 600
 318 driver: hdisk-state time 6.415 hdisk 0: free 868352 dumpers 0
 319 driver: QUITTING time 6.415 telling children to quit
 320 driver: send-cmd time 6.415 to dumper0: QUIT
 321 driver: send-cmd time 6.415 to dumper1: QUIT
 322 driver: send-cmd time 6.417 to dumper2: QUIT
 323 driver: send-cmd time 6.417 to dumper3: QUIT
 324 driver: send-cmd time 6.418 to taper: QUIT
 325 taper: DONE
 326 driver: FINISHED time 7.426
 327 amdump: end at Wed Jun 18 13:01:55 EDT 2008
 328 %%%% chunker_partial
 329 amdump: start at Fri Apr 10 07:47:59 PDT 2009
 330 amdump: datestamp 20090410
 331 amdump: starttime 20090410074759
 332 amdump: starttime-locale-independent 2009-04-10 07:47:59 PDT
 333 planner: pid 4108 executable /usr/lib/amanda/planner version 3.0.0
 334 planner: build: VERSION="Amanda-3.0.0"
 335 planner:        BUILT_DATE="Wed Apr 8 17:49:57 PDT 2009"
 336 planner:        BUILT_MACH="i386-pc-solaris2.10" BUILT_REV="16514"
 337 planner:        BUILT_BRANCH="Amanda_Enterprise_3_0" CC="gcc"
 338 planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
 339 planner:        libexecdir="/usr/lib" amlibexecdir="/usr/lib/amanda"
 340 planner:        mandir="/usr/share/man" AMANDA_TMPDIR="/tmp/amanda"
 341 planner:        AMANDA_DBGDIR="/var/log/amanda" CONFIG_DIR="/etc/amanda"
 342 planner:        DEV_PREFIX="/dev/dsk/" RDEV_PREFIX="/dev/rdsk/"
 343 planner:        DUMP="/usr/sbin/ufsdump" RESTORE="/usr/sbin/ufsrestore"
 344 planner:        VDUMP=UNDEF VRESTORE=UNDEF XFSDUMP=UNDEF XFSRESTORE=UNDEF
 345 planner:        VXDUMP=UNDEF VXRESTORE=UNDEF
 346 planner:        SAMBA_CLIENT="/usr/sfw/bin/smbclient"
 347 planner:        STAR="/opt/csw/bin/star" GNUTAR="/opt/csw/bin/gtar"
 348 planner:        COMPRESS_PATH="/usr/bin/gzip"
 349 planner:        UNCOMPRESS_PATH="/usr/bin/gzip" LPRCMD="/usr/bin/lp"
 350 planner:         MAILER=UNDEF listed_incr_dir="/var/lib/amanda/gnutar-lists"
 351 planner: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
 352 planner:        DEFAULT_TAPE_SERVER="localhost" DEFAULT_TAPE_DEVICE=""
 353 planner:        HAVE_MMAP NEED_STRSTR HAVE_SYSVSHM AMFLOCK_POSIX AMFLOCK_LOCKF
 354 planner:        AMFLOCK_LNLOCK SETPGRP_VOID ASSERTIONS AMANDA_DEBUG_DAYS=4
 355 planner:        BSD_SECURITY RSH_SECURITY USE_AMANDAHOSTS
 356 planner:        CLIENT_LOGIN="amandabackup" CHECK_USERID HAVE_GZIP
 357 planner:        COMPRESS_SUFFIX=".gz" COMPRESS_FAST_OPT="--fast"
 358 planner:        COMPRESS_BEST_OPT="--best" UNCOMPRESS_OPT="-dc"
 359 READING CONF INFO...
 360 planner: timestamp 20090410074759
 361 planner: time 0.001: startup took 0.001 secs
 362
 363 SENDING FLUSHES...
 364 ENDFLUSH
 365
 366 SETTING UP FOR ESTIMATES...
 367 planner: time 0.001: setting up estimates for localhost:/etc
 368 localhost:/etc overdue 14338 days for level 0
 369 setup_estimate: localhost:/etc: command 0, options: none    last_level 0 next_level0 -14338 level_days 0    getting estimates 0 (-2) 1 (-2) -1 (-2)
 370 planner: time 0.002: setting up estimates took 0.000 secs
 371
 372 GETTING ESTIMATES...
 373 planner time 0.113: got result for host localhost disk /etc: 0 -> 80822K, 1 -> 61440K, -1 -> -2K
 374 driver: pid 4109 executable /usr/lib/amanda/driver version 3.0.0
 375 driver: tape size 122880
 376 driver: adding holding disk 0 dir /var/lib/amanda/staging size 1215488 chunksize 1048576
 377 reserving 1215488 out of 1215488 for degraded-mode dumps
 378 driver: send-cmd time 0.010 to taper: START-TAPER 20090410074759
 379 driver: started dumper0 pid 4116
 380 driver: send-cmd time 0.012 to dumper0: START 20090410074759
 381 driver: started dumper1 pid 4117
 382 driver: send-cmd time 0.014 to dumper1: START 20090410074759
 383 driver: started dumper2 pid 4118
 384 driver: send-cmd time 0.016 to dumper2: START 20090410074759
 385 driver: started dumper3 pid 4119
 386 driver: send-cmd time 0.018 to dumper3: START 20090410074759
 387 driver: start time 0.018 inparallel 4 bandwidth 8000 diskspace 1215488  dir OBSOLETE datestamp 20090410074759 driver: drain-ends tapeq FIRST big-dumpers sssS
 388 taper: pid 4115 executable taper version 3.0.0
 389 dumper: pid 4116 executable dumper0 version 3.0.0
 390 planner: time 0.744: got partial result for host localhost disk /etc: 0 -> 80822K, 1 -> -1K, -1 -> -2K
 391 planner: time 0.744: got result for host localhost disk /etc: 0 -> 80822K, 1 -> -1K, -1 -> -2K
 392 planner: time 0.745: getting estimates took 0.742 secs
 393 FAILED QUEUE: empty
 394 DONE QUEUE:
 395   0: localhost  /etc
 396
 397 ANALYZING ESTIMATES...
 398 pondering localhost:/etc... next_level0 -14338 last_level 0 (due for level 0) (picking inclevel for degraded mode)   picklev: last night 0, so tonight level 1
 399 (no inc estimate)
 400   curr level 0 nsize 80822 csize 80822 total size 80921 total_lev0 80822 balanced-lev0size 11546
 401 INITIAL SCHEDULE (size 80921):
 402   localhost /etc pri 14339 lev 0 nsize 80822 csize 80822
 403
 404 DELAYING DUMPS IF NEEDED, total_size 80921, tape length 122880 mark 1
 405   delay: Total size now 80921.
 406
 407 PROMOTING DUMPS IF NEEDED, total_lev0 80822, balanced_size 11546...
 408 planner: time 0.745: analysis took 0.000 secs
 409
 410 GENERATING SCHEDULE:
 411 --------
 412 DUMP localhost ffffffff9efeffffffffff01 /etc 20090410074759 14339 0 1970:1:1:0:0:0 80822 80822 86 929 "Can't switch to degraded mode because an incremental estimate could not be performed"
 413 --------
 414 dumper: pid 4119 executable dumper3 version 3.0.0
 415 dumper: pid 4118 executable dumper2 version 3.0.0
 416 dumper: pid 4117 executable dumper1 version 3.0.0
 417 taper: using label `maitreyee-010' date `20090410074759'
 418 driver: result time 2.928 from taper: TAPER-OK
 419 driver: state time 2.937 free kps: 8000 space: 1215488 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
 420 driver: interface-state time 2.937 if default: free 8000
 421 driver: hdisk-state time 2.937 hdisk 0: free 1215488 dumpers 0
 422 driver: flush size 0
 423 driver: started chunker0 pid 4160
 424 driver: send-cmd time 2.954 to chunker0: START 20090410074759
 425 driver: send-cmd time 2.954 to chunker0: PORT-WRITE 00-00001 /var/lib/amanda/staging/20090410074759/localhost._etc.0 localhost ffffffff9efeffffffffff01 /etc 0 1970:1:1:0:0:0 1048576 APPLICATION 80896 |;auth=bsdtcp;index;
 426 chunker: pid 4160 executable chunker0 version 3.0.0
 427 driver: result time 3.000 from chunker0: PORT 11005
 428 driver: send-cmd time 3.001 to dumper0: PORT-DUMP 00-00001 11005 localhost ffffffff9efeffffffffff01 /etc /etc 0 1970:1:1:0:0:0 amsuntar X X X bsdtcp |"  <auth>bsdtcp</auth>\n  <record>YES</record>\n  <index>YES</index>\n  <backup-program>\n    <plugin>amsuntar</plugin>\n    <property>\n      <name>EXTENDED-HEADERS</name>\n      <value>NO</value>\n    </property>\n    <property>\n      <name>EXTENDED-ATTRIBUTES</name>\n      <value>NO</value>\n    </property>\n    <property>\n      <name>BLOCK-SIZE</name>\n      <value>64</value>\n    </property>\n  </backup-program>\n"
 429 driver: state time 3.034 free kps: 7071 space: 1134592 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 430 driver: interface-state time 3.034 if default: free 7071
 431 driver: hdisk-state time 3.034 hdisk 0: free 1134592 dumpers 1
 432 driver: state time 49.732 free kps: 7071 space: 1134592 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 433 driver: interface-state time 49.732 if default: free 7071
 434 driver: hdisk-state time 49.732 hdisk 0: free 1134592 dumpers 1
 435 driver: result time 49.732 from chunker0: RQ-MORE-DISK 00-00001
 436 driver: send-cmd time 49.733 to chunker0: CONTINUE 00-00001 /var/lib/amanda/staging/20090410074759/localhost._etc.0 1048576 4096
 437 dumper: kill index command
 438 driver: state time 49.901 free kps: 7071 space: 1130496 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 439 driver: interface-state time 49.901 if default: free 7071
 440 driver: hdisk-state time 49.901 hdisk 0: free 1130496 dumpers 1
 441 driver: result time 49.901 from dumper0: FAILED 00-00001 "[/usr/sbin/tar returned error]"
 442 driver: send-cmd time 49.901 to chunker0: FAILED 00-00001
 443 driver: state time 49.906 free kps: 7071 space: 1130496 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 444 driver: interface-state time 49.906 if default: free 7071
 445 driver: hdisk-state time 49.906 hdisk 0: free 1130496 dumpers 1
 446 driver: result time 49.906 from chunker0: PARTIAL 00-00001 80909 "[sec 46.789 kb 80909 kps 1729.9]"
 447 driver: finished-cmd time 49.907 chunker0 chunked localhost:/etc
 448 driver: started chunker0 pid 4184
 449 driver: send-cmd time 50.094 to chunker0: START 20090410074759
 450 driver: send-cmd time 50.094 to chunker0: PORT-WRITE 00-00002 /var/lib/amanda/staging/20090410074759/localhost._etc.0 localhost ffffffff9efeffffffffff01 /etc 0 1970:1:1:0:0:0 1048576 APPLICATION 84960 |;auth=bsdtcp;index;
 451 chunker: pid 4184 executable chunker0 version 3.0.0
 452 driver: result time 50.139 from chunker0: PORT 11035
 453 driver: send-cmd time 50.140 to dumper0: PORT-DUMP 00-00002 11035 localhost ffffffff9efeffffffffff01 /etc /etc 0 1970:1:1:0:0:0 amsuntar X X X bsdtcp |"  <auth>bsdtcp</auth>\n  <record>YES</record>\n  <index>YES</index>\n  <backup-program>\n    <plugin>amsuntar</plugin>\n    <property>\n      <name>EXTENDED-HEADERS</name>\n      <value>NO</value>\n    </property>\n    <property>\n      <name>EXTENDED-ATTRIBUTES</name>\n      <value>NO</value>\n    </property>\n    <property>\n      <name>BLOCK-SIZE</name>\n      <value>64</value>\n    </property>\n  </backup-program>\n"
 454 driver: state time 50.175 free kps: 7071 space: 1130528 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 455 driver: interface-state time 50.175 if default: free 7071
 456 driver: hdisk-state time 50.175 hdisk 0: free 1130528 dumpers 1
 457 driver: state time 84.740 free kps: 7071 space: 1130528 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 458 driver: interface-state time 84.740 if default: free 7071
 459 driver: hdisk-state time 84.740 hdisk 0: free 1130528 dumpers 1
 460 driver: result time 84.740 from dumper0: FAILED 00-00002 "[/usr/sbin/tar returned error]"
 461 driver: send-cmd time 84.740 to chunker0: FAILED 00-00002
 462 driver: state time 84.745 free kps: 7071 space: 1130528 taper: idle idle-dumpers: 3 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 463 driver: interface-state time 84.745 if default: free 7071
 464 driver: hdisk-state time 84.745 hdisk 0: free 1130528 dumpers 1
 465 driver: result time 84.745 from chunker0: PARTIAL 00-00002 80917 "[sec 34.565 kb 80917 kps 2341.9]"
 466 driver: finished-cmd time 84.745 chunker0 chunked localhost:/etc
 467 dumper: kill index command
 468 driver: send-cmd time 84.890 to taper: FILE-WRITE 00-00003 /var/lib/amanda/staging/20090410074759/localhost._etc.0 localhost /etc 0 20090410074759 0
 469 driver: startaflush: FIRST localhost /etc 80949 122880
 470 driver: state time 84.890 free kps: 8000 space: 1134539 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 471 driver: interface-state time 84.890 if default: free 8000
 472 driver: hdisk-state time 84.890 hdisk 0: free 1134539 dumpers 0
 473 driver: state time 84.891 free kps: 8000 space: 1134539 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 474 driver: interface-state time 84.891 if default: free 8000
 475 driver: hdisk-state time 84.891 hdisk 0: free 1134539 dumpers 0
 476 driver: result time 84.891 from taper: REQUEST-NEW-TAPE 00-00003
 477 driver: send-cmd time 84.891 to taper: NEW-TAPE
 478 driver: state time 84.906 free kps: 8000 space: 1134539 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 479 driver: interface-state time 84.906 if default: free 8000
 480 driver: hdisk-state time 84.906 hdisk 0: free 1134539 dumpers 0
 481 driver: result time 84.906 from taper: NEW-TAPE 00-00003 maitreyee-010
 482 driver: state time 114.910 free kps: 8000 space: 1134539 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 483 driver: interface-state time 114.910 if default: free 8000
 484 driver: hdisk-state time 114.910 hdisk 0: free 1134539 dumpers 0
 485 driver: result time 114.911 from taper: PARTDONE 00-00003 maitreyee-010 1 80917 "[sec 30.003926 kb 80917 kps 2696.880402]"
 486 driver: state time 114.911 free kps: 8000 space: 1134539 taper: writing idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 487 driver: interface-state time 114.911 if default: free 8000
 488 driver: hdisk-state time 114.911 hdisk 0: free 1134539 dumpers 0
 489 driver: result time 114.911 from taper: DONE 00-00003 INPUT-GOOD TAPE-GOOD "[sec 30.003926 kb 80917 kps 2696.880402]" "" ""
 490 driver: finished-cmd time 114.911 taper wrote localhost:/etc
 491 driver: state time 114.943 free kps: 8000 space: 1215488 taper: idle idle-dumpers: 4 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
 492 driver: interface-state time 114.943 if default: free 8000
 493 driver: hdisk-state time 114.943 hdisk 0: free 1215488 dumpers 0
 494 driver: QUITTING time 114.943 telling children to quit
 495 driver: send-cmd time 114.943 to dumper0: QUIT ""
 496 driver: send-cmd time 114.944 to dumper1: QUIT ""
 497 driver: send-cmd time 114.944 to dumper2: QUIT ""
 498 driver: send-cmd time 114.944 to dumper3: QUIT ""
 499 driver: send-cmd time 114.944 to taper: QUIT
 500 taper: DONE
 501 driver: FINISHED time 115.961
 502 amdump: end at Fri Apr 10 07:49:55 PDT 2009