Imported Upstream version 3.3.3
[debian/amanda] / installcheck / catalogs / taper-parallel-write.cat
1 # an amdump logfile to check amstatus's display of taper-parallel-write > 1
2
3 %F log/amdump
4 amdump: start at Wed Sep 19 14:35:30 PDT 2012
5 amdump: datestamp 20120919
6 amdump: starttime 20120919143530
7 amdump: starttime-locale-independent 2012-09-19 14:35:30 PDT
8 planner: pid 5286 executable /usr/lib/amanda/planner version 3.3.2
9 planner: build: VERSION="Amanda-3.3.2"
10 planner:        BUILT_DATE="Tue Jul 24 13:28:42 PDT 2012" BUILT_MACH=""
11 planner:        BUILT_REV="4847" BUILT_BRANCH="zmanda_3_3_2"
12 planner:        CC="i486-linux-gnu-gcc"
13 planner: paths: bindir="/usr/bin" sbindir="/usr/sbin"
14 planner:        libexecdir="/usr/lib" amlibexecdir="/usr/lib/amanda"
15 planner:        mandir="/usr/share/man" AMANDA_TMPDIR="/tmp/amanda"
16 planner:        AMANDA_DBGDIR="/var/log/amanda" CONFIG_DIR="/etc/amanda"
17 planner:        DEV_PREFIX="/dev/" RDEV_PREFIX="/dev/" DUMP="/sbin/dump"
18 planner:        RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF
19 planner:        XFSDUMP="/sbin/xfsdump" XFSRESTORE="/sbin/xfsrestore"
20 planner:        VXDUMP=UNDEF VXRESTORE=UNDEF
21 planner:        SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar"
22 planner:        COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip"
23 planner:         LPRCMD=UNDEF  MAILER=UNDEF
24 planner:        listed_incr_dir="/var/lib/amanda/gnutar-lists"
25 planner: defs:  DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1"
26 planner:        DEFAULT_TAPE_SERVER="localhost" DEFAULT_TAPE_DEVICE=""
27 planner:        NEED_STRSTR AMFLOCK_POSIX AMFLOCK_FLOCK AMFLOCK_LOCKF
28 planner:        AMFLOCK_LNLOCK SETPGRP_VOID ASSERTIONS AMANDA_DEBUG_DAYS=4
29 planner:        BSD_SECURITY USE_AMANDAHOSTS CLIENT_LOGIN="amandabackup"
30 planner:        CHECK_USERID HAVE_GZIP COMPRESS_SUFFIX=".gz"
31 planner:        COMPRESS_FAST_OPT="--fast" COMPRESS_BEST_OPT="--best"
32 planner:        UNCOMPRESS_OPT="-dc"
33 READING CONF INFO...
34 planner: timestamp 20120919143530
35 planner: tape_length is set from tape length (2445312 KB) * runtapes (1000) == 2445312000 KB
36 planner: time 0.000: startup took 0.000 secs
37
38 SENDING FLUSHES...
39 ENDFLUSH
40
41 SETTING UP FOR ESTIMATES...
42 planner: time 0.000: setting up estimates for localhost:/root/testfile/732mb
43 localhost:/root/testfile/732mb overdue 15603 days for level 0
44 setup_estimate: localhost:/root/testfile/732mb: command 0, options: none    last_level -1 next_level0 -15603 level_days 0    getting estimates 0 (-3) -1 (-3) -1 (-3)
45 planner: time 0.000: setting up estimates for centos6-43-client:/root/testfile/36mb
46 centos6-43-client:/root/testfile/36mb overdue 15603 days for level 0
47 setup_estimate: centos6-43-client:/root/testfile/36mb: command 0, options: none    last_level -1 next_level0 -15603 level_days 0    getting estimates 0 (-3) -1 (-3) -1 (-3)
48 planner: time 0.001: setting up estimates for qa-debian6-x64-anuj:/root/testfile/146mb
49 qa-debian6-x64-anuj:/root/testfile/146mb overdue 15603 days for level 0
50 setup_estimate: qa-debian6-x64-anuj:/root/testfile/146mb: command 0, options: none    last_level -1 next_level0 -15603 level_days 0    getting estimates 0 (-3) -1 (-3) -1 (-3)
51 planner: time 0.001: setting up estimates took 0.000 secs
52
53 GETTING ESTIMATES...
54 driver: pid 5287 executable /usr/lib/amanda/driver version 3.3.2
55 driver: tape size 2445312
56 reserving 0 out of 0 for degraded-mode dumps
57 driver: started dumper0 pid 5290
58 driver: send-cmd time 0.027 to dumper0: START 20120919143530
59 driver: started dumper1 pid 5292
60 driver: send-cmd time 0.048 to dumper1: START 20120919143530
61 driver: send-cmd time 0.049 to taper: START-TAPER worker0 20120919143530
62 driver: start time 0.049 inparallel 2 bandwidth 2000000 diskspace 0  dir OBSOLETE datestamp 20120919143530 driver: drain-ends tapeq LARGEST big-dumpers sS
63 driver: state time 0.049 free kps: 2000000 space: 0 taper: idle idle-dumpers: 2 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
64 driver: interface-state time 0.049 if default: free 2000000
65 driver: hdisk-state time 0.049
66 dumper: pid 5292 executable dumper1 version 3.3.2
67 dumper: pid 5290 executable dumper0 version 3.3.2
68 planner time 1.062: got result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K
69 planner time 1.079: got result for host centos6-43-client disk /root/testfile/36mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K
70 planner: time 1.143: got partial result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K
71 planner: time 1.163: got partial result for host centos6-43-client disk /root/testfile/36mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K
72 planner time 1.175: got result for host localhost disk /root/testfile/732mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K
73 planner: time 1.228: got partial result for host localhost disk /root/testfile/732mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K
74 planner: time 1.266: got partial result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 142298K, -1 -> -3K, -1 -> -3K
75 driver: state time 1.292 free kps: 2000000 space: 0 taper: idle idle-dumpers: 2 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: not-idle
76 driver: interface-state time 1.292 if default: free 2000000
77 driver: hdisk-state time 1.292
78 driver: result time 1.292 from taper: TAPER-OK worker0
79 planner: time 1.375: got partial result for host centos6-43-client disk /root/testfile/36mb: 0 -> 35576K, -1 -> -3K, -1 -> -3K
80 planner: time 1.383: got partial result for host localhost disk /root/testfile/732mb: 0 -> 715055K, -1 -> -3K, -1 -> -3K
81 planner: time 2.270: got result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 142298K, -1 -> -3K, -1 -> -3K
82 planner: time 2.379: got result for host centos6-43-client disk /root/testfile/36mb: 0 -> 35576K, -1 -> -3K, -1 -> -3K
83 planner: time 2.385: got result for host localhost disk /root/testfile/732mb: 0 -> 715055K, -1 -> -3K, -1 -> -3K
84 planner: time 2.386: getting estimates took 2.384 secs
85 FAILED QUEUE: empty
86 DONE QUEUE:
87   0: qa-debian6-x64-anuj /root/testfile/146mb
88   1: centos6-43-client /root/testfile/36mb
89   2: localhost  /root/testfile/732mb
90
91 ANALYZING ESTIMATES...
92 pondering qa-debian6-x64-anuj:/root/testfile/146mb... next_level0 -15603 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
93   curr level 0 nsize 142298 csize 142298 total size 142397 total_lev0 142298 balanced-lev0size 28459
94 pondering centos6-43-client:/root/testfile/36mb... next_level0 -15603 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
95   curr level 0 nsize 35576 csize 35576 total size 178006 total_lev0 177874 balanced-lev0size 35574
96 pondering localhost:/root/testfile/732mb... next_level0 -15603 last_level -1 (due for level 0) (new disk, can't switch to degraded mode)
97   curr level 0 nsize 715055 csize 715055 total size 893094 total_lev0 892929 balanced-lev0size 178585
98 INITIAL SCHEDULE (size 893094):
99   localhost /root/testfile/732mb pri 15604 lev 0 nsize 715055 csize 715055
100   qa-debian6-x64-anuj /root/testfile/146mb pri 15604 lev 0 nsize 142298 csize 142298
101   centos6-43-client /root/testfile/36mb pri 15604 lev 0 nsize 35576 csize 35576
102
103 DELAYING DUMPS IF NEEDED, total_size 893094, tape length 2445312000 mark 1
104   delay: Total size now 893094.
105
106 PROMOTING DUMPS IF NEEDED, total_lev0 892929, balanced_size 178585...
107 planner: time 2.386: analysis took 0.000 secs
108
109 GENERATING SCHEDULE:
110 --------
111 DUMP localhost ffffffff9efefbffffffffff1f /root/testfile/732mb 20120919143530 15604 0 1970:1:1:0:0:0 715055 715055 698 1024 "Skipping: new disk can't be dumped in degraded mode"
112 DUMP qa-debian6-x64-anuj ffffffff9efefbffffffffff1f /root/testfile/146mb 20120919143530 15604 0 1970:1:1:0:0:0 142298 142298 138 1024 "Skipping: new disk can't be dumped in degraded mode"
113 DUMP centos6-43-client ffffffff9efefbffffffffff1f /root/testfile/36mb 20120919143530 15604 0 1970:1:1:0:0:0 35576 35576 34 1024 "Skipping: new disk can't be dumped in degraded mode"
114 --------
115 driver: flush size 0
116 find diskspace: not enough diskspace. Left with 715104 K
117 find diskspace: not enough diskspace. Left with 142336 K
118 find diskspace: not enough diskspace. Left with 35616 K
119 driver: send-cmd time 2.399 to taper: PORT-WRITE worker0 00-00001 localhost /root/testfile/732mb 0 20120919143530 "" "" "" 0 "" "" "" "" AMANDA
120 driver: state time 2.399 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-diskspace
121 driver: interface-state time 2.399 if default: free 1998976
122 driver: hdisk-state time 2.399
123 driver: state time 2.412 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-diskspace
124 driver: interface-state time 2.412 if default: free 1998976
125 driver: hdisk-state time 2.412
126 driver: result time 2.412 from taper: PORT worker0 00-00001 55940 "127.0.0.1:43682"
127 driver: send-cmd time 2.413 to dumper1: PORT-DUMP 00-00001 55940 localhost ffffffff9efefbffffffffff1f /root/testfile/732mb /root/testfile/732mb 0 1970:1:1:0:0:0 amgtar "" "" "" "" bsdtcp AMANDA 127.0.0.1:43682 20 |"  <auth>bsdtcp</auth>\n  <record>YES</record>\n  <index>YES</index>\n  <datapath>AMANDA</datapath>\n    <property>\n      <name>zmc-show-advanced</name>\n      <value>off</value>\n    </property>\n    <property>\n      <name>zmc-amanda-app</name>\n      <value>gtar</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-version</name>\n      <value>3.3.2</value>\n    </property>\n    <property>\n      <name>zmc-disklist</name>\n      <value>amstatus_test_3</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-app</name>\n      <value>1.23</value>\n    </property>\n    <property>\n      <name>zmc-version</name>\n      <value>3</value>\n    </property>\n    <property>\n      <name>last-modified-time</name>\n      <value encoding=\"raw\" raw=\"MjAxMi0wOS0xOSAxNDoyODo0OA==\">2012-09-19_14:28:48</value>\n    </property>\n    <property>\n      <name>zmc-type</name>\n      <value>unix</value>\n    </property>\n    <property>\n      <name>last-modified-by</name>\n      <value>admin</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-platform</name>\n      <value>6.0.5</value>\n    </property>\n    <property>\n      <name>creation-time</name>\n      <value encoding=\"raw\" raw=\"MjAxMi0wOS0xOSAxNDoyODo0OA==\">2012-09-19_14:28:48</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-date</name>\n      <value>20120919142848</value>\n    </property>\n    <property>\n      <name>zmc-occ</name>\n      <value>80901283870</value>\n    </property>\n  <backup-program>\n    <plugin>amgtar</plugin>\n    <property>\n      <name>sparse</name>\n      <value>yes</value>\n    </property>\n    <property>\n      <name>one-file-system</name>\n      <value>yes</value>\n    </property>\n    <property>\n      <name>check-device</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>xattrs</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>acls</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>selinux</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>atime-preserve</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>tar-blocksize</name>\n      <value>128</value>\n    </property>\n  </backup-program>\n"
128 taper: status file localhost /root/testfile/732mb:/tmp/amanda/taper_status_file_T2sTK0
129 driver: state time 2.577 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
130 driver: interface-state time 2.577 if default: free 1998976
131 driver: hdisk-state time 2.577
132 driver: result time 2.577 from taper: REQUEST-NEW-TAPE 00-00001
133 driver: send-cmd time 2.577 to taper: START-SCAN worker0 00-00001
134 driver: send-cmd time 2.577 to taper: NEW-TAPE worker0 00-00001
135 taper: wrote label 'amstatus_test_3-AA-001'
136 driver: state time 2.605 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
137 driver: interface-state time 2.605 if default: free 1998976
138 driver: hdisk-state time 2.605
139 driver: result time 2.605 from taper: NEW-TAPE 00-00001 amstatus_test_3-AA-001
140 driver: send-cmd time 2.605 to taper: START-TAPER worker1 20120919143530
141 driver: state time 2.638 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
142 driver: interface-state time 2.638 if default: free 1998976
143 driver: hdisk-state time 2.638
144 driver: result time 2.638 from taper: TAPER-OK worker1
145 driver: send-cmd time 2.638 to taper: PORT-WRITE worker1 01-00002 centos6-43-client /root/testfile/36mb 0 20120919143530 "" "" "" 0 "" "" "" "" AMANDA
146 driver: state time 2.638 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
147 driver: interface-state time 2.638 if default: free 1997952
148 driver: hdisk-state time 2.638
149 driver: state time 2.646 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
150 driver: interface-state time 2.646 if default: free 1997952
151 driver: hdisk-state time 2.646
152 driver: result time 2.646 from taper: PORT worker1 01-00002 51317 "127.0.0.1:35712"
153 driver: send-cmd time 2.646 to dumper0: PORT-DUMP 01-00002 51317 centos6-43-client ffffffff9efefbffffffffff1f /root/testfile/36mb /root/testfile/36mb 0 1970:1:1:0:0:0 amgtar "" "" "" "" bsdtcp AMANDA 127.0.0.1:35712 20 |"  <auth>bsdtcp</auth>\n  <record>YES</record>\n  <index>YES</index>\n  <datapath>AMANDA</datapath>\n    <property>\n      <name>zmc-show-advanced</name>\n      <value>off</value>\n    </property>\n    <property>\n      <name>zmc-amanda-app</name>\n      <value>gtar</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-version</name>\n      <value>3.3.2</value>\n    </property>\n    <property>\n      <name>zmc-disklist</name>\n      <value>amstatus_test_3</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-app</name>\n      <value>1.23</value>\n    </property>\n    <property>\n      <name>zmc-version</name>\n      <value>3</value>\n    </property>\n    <property>\n      <name>last-modified-time</name>\n      <value encoding=\"raw\" raw=\"MjAxMi0wOS0xOSAxNDoyOToyMQ==\">2012-09-19_14:29:21</value>\n    </property>\n    <property>\n      <name>zmc-type</name>\n      <value>unix</value>\n    </property>\n    <property>\n      <name>last-modified-by</name>\n      <value>admin</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-platform</name>\n      <value encoding=\"raw\" raw=\"Q2VudE9TICA2LjMgKEZpbmFsKQ==\">CentOS__6.3_(Final)</value>\n    </property>\n    <property>\n      <name>creation-time</name>\n      <value encoding=\"raw\" raw=\"MjAxMi0wOS0xOSAxNDoyOToyMQ==\">2012-09-19_14:29:21</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-date</name>\n      <value>20120919142922</value>\n    </property>\n    <property>\n      <name>zmc-occ</name>\n      <value>80901619884</value>\n    </property>\n  <backup-program>\n    <plugin>amgtar</plugin>\n    <property>\n      <name>sparse</name>\n      <value>yes</value>\n    </property>\n    <property>\n      <name>one-file-system</name>\n      <value>yes</value>\n    </property>\n    <property>\n      <name>check-device</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>xattrs</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>acls</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>selinux</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>atime-preserve</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>tar-blocksize</name>\n      <value>128</value>\n    </property>\n  </backup-program>\n"
154 taper: status file centos6-43-client /root/testfile/36mb:/tmp/amanda/taper_status_file_EIIMMc
155 driver: state time 2.797 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
156 driver: interface-state time 2.797 if default: free 1997952
157 driver: hdisk-state time 2.797
158 driver: result time 2.797 from taper: REQUEST-NEW-TAPE 01-00002
159 driver: send-cmd time 2.797 to taper: START-SCAN worker1 01-00002
160 driver: send-cmd time 2.797 to taper: NEW-TAPE worker1 01-00002
161 taper: wrote label 'amstatus_test_3-AA-002'
162 driver: state time 2.876 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
163 driver: interface-state time 2.876 if default: free 1997952
164 driver: hdisk-state time 2.876
165 driver: result time 2.876 from taper: NEW-TAPE 01-00002 amstatus_test_3-AA-002
166 driver: state time 6.224 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
167 driver: interface-state time 6.224 if default: free 1997952
168 driver: hdisk-state time 6.224
169 driver: result time 6.224 from taper: PARTDONE 01-00002 amstatus_test_3-AA-002 1 35584 "[sec 3.338940 bytes 36438016 kps 10657.274046]"
170 driver: state time 6.226 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
171 driver: interface-state time 6.226 if default: free 1997952
172 driver: hdisk-state time 6.226
173 driver: result time 6.226 from dumper0: DONE 01-00002 35584 35584 4 "[sec 3.505 kb 35584 kps 10150.8 orig-kb 35584]"
174 driver: finished-cmd time 6.226 dumper0 dumped centos6-43-client:/root/testfile/36mb
175 driver: send-cmd time 6.226 to taper: DONE worker1 01-00002 35584
176 driver: state time 6.240 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
177 driver: interface-state time 6.240 if default: free 1997952
178 driver: hdisk-state time 6.240
179 driver: result time 6.240 from taper: DUMPER-STATUS 01-00002
180 driver: send-cmd time 6.240 to taper: DONE worker1 01-00002 35584
181 driver: state time 6.243 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
182 driver: interface-state time 6.243 if default: free 1997952
183 driver: hdisk-state time 6.243
184 driver: result time 6.243 from taper: DONE 01-00002 INPUT-GOOD TAPE-GOOD "[sec 4.000000 bytes 36438016 kps 8896.000000 orig-kb 35584]" "" ""
185 driver: finished-cmd time 6.243 taper wrote centos6-43-client:/root/testfile/36mb
186 driver: send-cmd time 6.243 to taper: CLOSE-VOLUME worker1
187 driver: send-cmd time 6.244 to taper: PORT-WRITE worker1 01-00003 qa-debian6-x64-anuj /root/testfile/146mb 0 20120919143530 "" "" "" 0 "" "" "" "" AMANDA
188 driver: state time 6.244 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
189 driver: interface-state time 6.244 if default: free 1997952
190 driver: hdisk-state time 6.244
191 driver: state time 6.263 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
192 driver: interface-state time 6.263 if default: free 1997952
193 driver: hdisk-state time 6.263
194 driver: result time 6.263 from taper: PORT worker1 01-00003 35040 "127.0.0.1:54910"
195 driver: send-cmd time 6.263 to dumper0: PORT-DUMP 01-00003 35040 qa-debian6-x64-anuj ffffffff9efefbffffffffff1f /root/testfile/146mb /root/testfile/146mb 0 1970:1:1:0:0:0 amgtar "" "" "" "" bsdtcp AMANDA 127.0.0.1:54910 20 |"  <auth>bsdtcp</auth>\n  <record>YES</record>\n  <index>YES</index>\n  <datapath>AMANDA</datapath>\n    <property>\n      <name>zmc-show-advanced</name>\n      <value>off</value>\n    </property>\n    <property>\n      <name>zmc-amanda-app</name>\n      <value>gtar</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-version</name>\n      <value>3.3.2</value>\n    </property>\n    <property>\n      <name>zmc-disklist</name>\n      <value>amstatus_test_3</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-app</name>\n      <value>1.23</value>\n    </property>\n    <property>\n      <name>zmc-version</name>\n      <value>3</value>\n    </property>\n    <property>\n      <name>last-modified-time</name>\n      <value encoding=\"raw\" raw=\"MjAxMi0wOS0xOSAxNDoyOTo1Mg==\">2012-09-19_14:29:52</value>\n    </property>\n    <property>\n      <name>zmc-type</name>\n      <value>unix</value>\n    </property>\n    <property>\n      <name>last-modified-by</name>\n      <value>admin</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-platform</name>\n      <value>6.0.5</value>\n    </property>\n    <property>\n      <name>creation-time</name>\n      <value encoding=\"raw\" raw=\"MjAxMi0wOS0xOSAxNDoyOTo1Mg==\">2012-09-19_14:29:52</value>\n    </property>\n    <property>\n      <name>zmc-amcheck-date</name>\n      <value>20120919143206</value>\n    </property>\n    <property>\n      <name>zmc-occ</name>\n      <value>80901928569</value>\n    </property>\n  <backup-program>\n    <plugin>amgtar</plugin>\n    <property>\n      <name>sparse</name>\n      <value>yes</value>\n    </property>\n    <property>\n      <name>one-file-system</name>\n      <value>yes</value>\n    </property>\n    <property>\n      <name>check-device</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>xattrs</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>acls</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>selinux</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>atime-preserve</name>\n      <value>no</value>\n    </property>\n    <property>\n      <name>tar-blocksize</name>\n      <value>128</value>\n    </property>\n  </backup-program>\n"
196 taper: status file qa-debian6-x64-anuj /root/testfile/146mb:/tmp/amanda/taper_status_file_R4zzdU
197 driver: state time 10.772 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
198 driver: interface-state time 10.772 if default: free 1997952
199 driver: hdisk-state time 10.772
200 driver: result time 10.772 from taper: REQUEST-NEW-TAPE 01-00003
201 driver: state time 42.899 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
202 driver: interface-state time 42.899 if default: free 1997952
203 driver: hdisk-state time 42.899
204 driver: result time 42.900 from dumper1: DONE 00-00001 715072 715072 40 "[sec 40.387 kb 715072 kps 17705.5 orig-kb 715072]"
205 driver: finished-cmd time 42.900 dumper1 dumped localhost:/root/testfile/732mb
206 driver: send-cmd time 42.900 to taper: DONE worker0 00-00001 715072
207 driver: state time 42.903 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
208 driver: interface-state time 42.903 if default: free 1997952
209 driver: hdisk-state time 42.903
210 driver: result time 42.903 from taper: PARTDONE 00-00001 amstatus_test_3-AA-001 1 715072 "[sec 40.253966 bytes 732233728 kps 17764.013439]"
211 driver: state time 42.928 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
212 driver: interface-state time 42.928 if default: free 1997952
213 driver: hdisk-state time 42.928
214 driver: result time 42.928 from taper: DUMPER-STATUS 00-00001
215 driver: send-cmd time 42.928 to taper: DONE worker0 00-00001 715072
216 driver: state time 42.944 free kps: 1997952 space: 0 taper: writing idle-dumpers: 0 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
217 driver: interface-state time 42.944 if default: free 1997952
218 driver: hdisk-state time 42.944
219 driver: result time 42.944 from taper: DONE 00-00001 INPUT-GOOD TAPE-GOOD "[sec 41.000000 bytes 732233728 kps 17440.780488 orig-kb 715072]" "" ""
220 driver: finished-cmd time 42.944 taper wrote localhost:/root/testfile/732mb
221 driver: send-cmd time 42.944 to taper: CLOSE-VOLUME worker0
222 driver: send-cmd time 42.947 to taper: TAKE-SCRIBE-FROM worker1 01-00003 worker0
223 driver: state time 42.947 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
224 driver: interface-state time 42.947 if default: free 1998976
225 driver: hdisk-state time 42.947
226 driver: state time 42.966 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
227 driver: interface-state time 42.966 if default: free 1998976
228 driver: hdisk-state time 42.966
229 driver: result time 42.966 from taper: REQUEST-NEW-TAPE 01-00003
230 driver: send-cmd time 42.966 to taper: START-SCAN worker1 01-00003
231 driver: send-cmd time 42.966 to taper: NEW-TAPE worker1 01-00003
232 taper: wrote label 'amstatus_test_3-AA-003'
233 driver: state time 43.011 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
234 driver: interface-state time 43.011 if default: free 1998976
235 driver: hdisk-state time 43.011
236 driver: result time 43.011 from taper: NEW-TAPE 01-00003 amstatus_test_3-AA-003
237 driver: send-cmd time 43.011 to taper: START-TAPER worker0 20120919143530
238 driver: state time 43.064 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
239 driver: interface-state time 43.064 if default: free 1998976
240 driver: hdisk-state time 43.064
241 driver: result time 43.064 from taper: TAPER-OK worker0
242 driver: state time 82.686 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
243 driver: interface-state time 82.686 if default: free 1998976
244 driver: hdisk-state time 82.686
245 driver: result time 82.686 from taper: PARTDONE 01-00003 amstatus_test_3-AA-003 1 142336 "[sec 39.671814 bytes 145752064 kps 3587.836922]"
246 driver: state time 82.692 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
247 driver: interface-state time 82.692 if default: free 1998976
248 driver: hdisk-state time 82.692
249 driver: result time 82.692 from dumper0: DONE 01-00003 142336 142336 73 "[sec 72.703 kb 142336 kps 1957.8 orig-kb 142336]"
250 driver: finished-cmd time 82.692 dumper0 dumped qa-debian6-x64-anuj:/root/testfile/146mb
251 driver: send-cmd time 82.692 to taper: DONE worker1 01-00003 142336
252 driver: state time 82.694 free kps: 1998976 space: 0 taper: writing idle-dumpers: 1 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
253 driver: interface-state time 82.694 if default: free 1998976
254 driver: hdisk-state time 82.694
255 driver: result time 82.694 from taper: DONE 01-00003 INPUT-GOOD TAPE-GOOD "[sec 72.000000 bytes 145752064 kps 1976.888889]" "" ""
256 driver: finished-cmd time 82.694 taper wrote qa-debian6-x64-anuj:/root/testfile/146mb
257 driver: send-cmd time 82.694 to taper: CLOSE-VOLUME worker1
258 driver: state time 82.695 free kps: 2000000 space: 0 taper: idle idle-dumpers: 2 qlen tapeq: 0 runq: 0 roomq: 0 wakeup: 0 driver-idle: no-dumpers
259 driver: interface-state time 82.695 if default: free 2000000
260 driver: hdisk-state time 82.695
261 driver: QUITTING time 82.695 telling children to quit
262 driver: send-cmd time 82.695 to dumper0: QUIT ""
263 driver: send-cmd time 82.695 to dumper1: QUIT ""
264 driver: send-cmd time 82.698 to taper: QUIT
265 driver: FINISHED time 83.698
266 amdump: end at Wed Sep 19 14:36:53 PDT 2012