X-Git-Url: https://git.gag.com/?a=blobdiff_plain;f=installcheck%2Fcatalogs%2Ftaper-parallel-write.cat;fp=installcheck%2Fcatalogs%2Ftaper-parallel-write.cat;h=e2a6f91de37a1066112fabcf2a4f96dc093b73d6;hb=691567b16c13087b31ee4c2b6d038e57872fae82;hp=0000000000000000000000000000000000000000;hpb=cc7d7b45afc706099acf7ff2490ec5667d370651;p=debian%2Famanda diff --git a/installcheck/catalogs/taper-parallel-write.cat b/installcheck/catalogs/taper-parallel-write.cat new file mode 100644 index 0000000..e2a6f91 --- /dev/null +++ b/installcheck/catalogs/taper-parallel-write.cat @@ -0,0 +1,266 @@ +# an amdump logfile to check amstatus's display of taper-parallel-write > 1 + +%F log/amdump +amdump: start at Wed Sep 19 14:35:30 PDT 2012 +amdump: datestamp 20120919 +amdump: starttime 20120919143530 +amdump: starttime-locale-independent 2012-09-19 14:35:30 PDT +planner: pid 5286 executable /usr/lib/amanda/planner version 3.3.2 +planner: build: VERSION="Amanda-3.3.2" +planner: BUILT_DATE="Tue Jul 24 13:28:42 PDT 2012" BUILT_MACH="" +planner: BUILT_REV="4847" BUILT_BRANCH="zmanda_3_3_2" +planner: CC="i486-linux-gnu-gcc" +planner: paths: bindir="/usr/bin" sbindir="/usr/sbin" +planner: libexecdir="/usr/lib" amlibexecdir="/usr/lib/amanda" +planner: mandir="/usr/share/man" AMANDA_TMPDIR="/tmp/amanda" +planner: AMANDA_DBGDIR="/var/log/amanda" CONFIG_DIR="/etc/amanda" +planner: DEV_PREFIX="/dev/" RDEV_PREFIX="/dev/" DUMP="/sbin/dump" +planner: RESTORE="/sbin/restore" VDUMP=UNDEF VRESTORE=UNDEF +planner: XFSDUMP="/sbin/xfsdump" XFSRESTORE="/sbin/xfsrestore" +planner: VXDUMP=UNDEF VXRESTORE=UNDEF +planner: SAMBA_CLIENT="/usr/bin/smbclient" GNUTAR="/bin/tar" +planner: COMPRESS_PATH="/bin/gzip" UNCOMPRESS_PATH="/bin/gzip" +planner: LPRCMD=UNDEF MAILER=UNDEF +planner: listed_incr_dir="/var/lib/amanda/gnutar-lists" +planner: defs: DEFAULT_SERVER="localhost" DEFAULT_CONFIG="DailySet1" +planner: DEFAULT_TAPE_SERVER="localhost" DEFAULT_TAPE_DEVICE="" +planner: NEED_STRSTR AMFLOCK_POSIX AMFLOCK_FLOCK AMFLOCK_LOCKF +planner: AMFLOCK_LNLOCK SETPGRP_VOID ASSERTIONS AMANDA_DEBUG_DAYS=4 +planner: BSD_SECURITY USE_AMANDAHOSTS CLIENT_LOGIN="amandabackup" +planner: CHECK_USERID HAVE_GZIP COMPRESS_SUFFIX=".gz" +planner: COMPRESS_FAST_OPT="--fast" COMPRESS_BEST_OPT="--best" +planner: UNCOMPRESS_OPT="-dc" +READING CONF INFO... +planner: timestamp 20120919143530 +planner: tape_length is set from tape length (2445312 KB) * runtapes (1000) == 2445312000 KB +planner: time 0.000: startup took 0.000 secs + +SENDING FLUSHES... +ENDFLUSH + +SETTING UP FOR ESTIMATES... +planner: time 0.000: setting up estimates for localhost:/root/testfile/732mb +localhost:/root/testfile/732mb overdue 15603 days for level 0 +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) +planner: time 0.000: setting up estimates for centos6-43-client:/root/testfile/36mb +centos6-43-client:/root/testfile/36mb overdue 15603 days for level 0 +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) +planner: time 0.001: setting up estimates for qa-debian6-x64-anuj:/root/testfile/146mb +qa-debian6-x64-anuj:/root/testfile/146mb overdue 15603 days for level 0 +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) +planner: time 0.001: setting up estimates took 0.000 secs + +GETTING ESTIMATES... +driver: pid 5287 executable /usr/lib/amanda/driver version 3.3.2 +driver: tape size 2445312 +reserving 0 out of 0 for degraded-mode dumps +driver: started dumper0 pid 5290 +driver: send-cmd time 0.027 to dumper0: START 20120919143530 +driver: started dumper1 pid 5292 +driver: send-cmd time 0.048 to dumper1: START 20120919143530 +driver: send-cmd time 0.049 to taper: START-TAPER worker0 20120919143530 +driver: start time 0.049 inparallel 2 bandwidth 2000000 diskspace 0 dir OBSOLETE datestamp 20120919143530 driver: drain-ends tapeq LARGEST big-dumpers sS +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 +driver: interface-state time 0.049 if default: free 2000000 +driver: hdisk-state time 0.049 +dumper: pid 5292 executable dumper1 version 3.3.2 +dumper: pid 5290 executable dumper0 version 3.3.2 +planner time 1.062: got result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K +planner time 1.079: got result for host centos6-43-client disk /root/testfile/36mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K +planner: time 1.143: got partial result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K +planner: time 1.163: got partial result for host centos6-43-client disk /root/testfile/36mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K +planner time 1.175: got result for host localhost disk /root/testfile/732mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K +planner: time 1.228: got partial result for host localhost disk /root/testfile/732mb: 0 -> 1000000K, -1 -> -3K, -1 -> -3K +planner: time 1.266: got partial result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 142298K, -1 -> -3K, -1 -> -3K +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 +driver: interface-state time 1.292 if default: free 2000000 +driver: hdisk-state time 1.292 +driver: result time 1.292 from taper: TAPER-OK worker0 +planner: time 1.375: got partial result for host centos6-43-client disk /root/testfile/36mb: 0 -> 35576K, -1 -> -3K, -1 -> -3K +planner: time 1.383: got partial result for host localhost disk /root/testfile/732mb: 0 -> 715055K, -1 -> -3K, -1 -> -3K +planner: time 2.270: got result for host qa-debian6-x64-anuj disk /root/testfile/146mb: 0 -> 142298K, -1 -> -3K, -1 -> -3K +planner: time 2.379: got result for host centos6-43-client disk /root/testfile/36mb: 0 -> 35576K, -1 -> -3K, -1 -> -3K +planner: time 2.385: got result for host localhost disk /root/testfile/732mb: 0 -> 715055K, -1 -> -3K, -1 -> -3K +planner: time 2.386: getting estimates took 2.384 secs +FAILED QUEUE: empty +DONE QUEUE: + 0: qa-debian6-x64-anuj /root/testfile/146mb + 1: centos6-43-client /root/testfile/36mb + 2: localhost /root/testfile/732mb + +ANALYZING ESTIMATES... +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) + curr level 0 nsize 142298 csize 142298 total size 142397 total_lev0 142298 balanced-lev0size 28459 +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) + curr level 0 nsize 35576 csize 35576 total size 178006 total_lev0 177874 balanced-lev0size 35574 +pondering localhost:/root/testfile/732mb... next_level0 -15603 last_level -1 (due for level 0) (new disk, can't switch to degraded mode) + curr level 0 nsize 715055 csize 715055 total size 893094 total_lev0 892929 balanced-lev0size 178585 +INITIAL SCHEDULE (size 893094): + localhost /root/testfile/732mb pri 15604 lev 0 nsize 715055 csize 715055 + qa-debian6-x64-anuj /root/testfile/146mb pri 15604 lev 0 nsize 142298 csize 142298 + centos6-43-client /root/testfile/36mb pri 15604 lev 0 nsize 35576 csize 35576 + +DELAYING DUMPS IF NEEDED, total_size 893094, tape length 2445312000 mark 1 + delay: Total size now 893094. + +PROMOTING DUMPS IF NEEDED, total_lev0 892929, balanced_size 178585... +planner: time 2.386: analysis took 0.000 secs + +GENERATING SCHEDULE: +-------- +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" +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" +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" +-------- +driver: flush size 0 +find diskspace: not enough diskspace. Left with 715104 K +find diskspace: not enough diskspace. Left with 142336 K +find diskspace: not enough diskspace. Left with 35616 K +driver: send-cmd time 2.399 to taper: PORT-WRITE worker0 00-00001 localhost /root/testfile/732mb 0 20120919143530 "" "" "" 0 "" "" "" "" AMANDA +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 +driver: interface-state time 2.399 if default: free 1998976 +driver: hdisk-state time 2.399 +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 +driver: interface-state time 2.412 if default: free 1998976 +driver: hdisk-state time 2.412 +driver: result time 2.412 from taper: PORT worker0 00-00001 55940 "127.0.0.1:43682" +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 |" bsdtcp\n YES\n YES\n AMANDA\n \n zmc-show-advanced\n off\n \n \n zmc-amanda-app\n gtar\n \n \n zmc-amcheck-version\n 3.3.2\n \n \n zmc-disklist\n amstatus_test_3\n \n \n zmc-amcheck-app\n 1.23\n \n \n zmc-version\n 3\n \n \n last-modified-time\n 2012-09-19_14:28:48\n \n \n zmc-type\n unix\n \n \n last-modified-by\n admin\n \n \n zmc-amcheck-platform\n 6.0.5\n \n \n creation-time\n 2012-09-19_14:28:48\n \n \n zmc-amcheck-date\n 20120919142848\n \n \n zmc-occ\n 80901283870\n \n \n amgtar\n \n sparse\n yes\n \n \n one-file-system\n yes\n \n \n check-device\n no\n \n \n xattrs\n no\n \n \n acls\n no\n \n \n selinux\n no\n \n \n atime-preserve\n no\n \n \n tar-blocksize\n 128\n \n \n" +taper: status file localhost /root/testfile/732mb:/tmp/amanda/taper_status_file_T2sTK0 +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 +driver: interface-state time 2.577 if default: free 1998976 +driver: hdisk-state time 2.577 +driver: result time 2.577 from taper: REQUEST-NEW-TAPE 00-00001 +driver: send-cmd time 2.577 to taper: START-SCAN worker0 00-00001 +driver: send-cmd time 2.577 to taper: NEW-TAPE worker0 00-00001 +taper: wrote label 'amstatus_test_3-AA-001' +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 +driver: interface-state time 2.605 if default: free 1998976 +driver: hdisk-state time 2.605 +driver: result time 2.605 from taper: NEW-TAPE 00-00001 amstatus_test_3-AA-001 +driver: send-cmd time 2.605 to taper: START-TAPER worker1 20120919143530 +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 +driver: interface-state time 2.638 if default: free 1998976 +driver: hdisk-state time 2.638 +driver: result time 2.638 from taper: TAPER-OK worker1 +driver: send-cmd time 2.638 to taper: PORT-WRITE worker1 01-00002 centos6-43-client /root/testfile/36mb 0 20120919143530 "" "" "" 0 "" "" "" "" AMANDA +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 +driver: interface-state time 2.638 if default: free 1997952 +driver: hdisk-state time 2.638 +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 +driver: interface-state time 2.646 if default: free 1997952 +driver: hdisk-state time 2.646 +driver: result time 2.646 from taper: PORT worker1 01-00002 51317 "127.0.0.1:35712" +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 |" bsdtcp\n YES\n YES\n AMANDA\n \n zmc-show-advanced\n off\n \n \n zmc-amanda-app\n gtar\n \n \n zmc-amcheck-version\n 3.3.2\n \n \n zmc-disklist\n amstatus_test_3\n \n \n zmc-amcheck-app\n 1.23\n \n \n zmc-version\n 3\n \n \n last-modified-time\n 2012-09-19_14:29:21\n \n \n zmc-type\n unix\n \n \n last-modified-by\n admin\n \n \n zmc-amcheck-platform\n CentOS__6.3_(Final)\n \n \n creation-time\n 2012-09-19_14:29:21\n \n \n zmc-amcheck-date\n 20120919142922\n \n \n zmc-occ\n 80901619884\n \n \n amgtar\n \n sparse\n yes\n \n \n one-file-system\n yes\n \n \n check-device\n no\n \n \n xattrs\n no\n \n \n acls\n no\n \n \n selinux\n no\n \n \n atime-preserve\n no\n \n \n tar-blocksize\n 128\n \n \n" +taper: status file centos6-43-client /root/testfile/36mb:/tmp/amanda/taper_status_file_EIIMMc +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 +driver: interface-state time 2.797 if default: free 1997952 +driver: hdisk-state time 2.797 +driver: result time 2.797 from taper: REQUEST-NEW-TAPE 01-00002 +driver: send-cmd time 2.797 to taper: START-SCAN worker1 01-00002 +driver: send-cmd time 2.797 to taper: NEW-TAPE worker1 01-00002 +taper: wrote label 'amstatus_test_3-AA-002' +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 +driver: interface-state time 2.876 if default: free 1997952 +driver: hdisk-state time 2.876 +driver: result time 2.876 from taper: NEW-TAPE 01-00002 amstatus_test_3-AA-002 +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 +driver: interface-state time 6.224 if default: free 1997952 +driver: hdisk-state time 6.224 +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]" +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 +driver: interface-state time 6.226 if default: free 1997952 +driver: hdisk-state time 6.226 +driver: result time 6.226 from dumper0: DONE 01-00002 35584 35584 4 "[sec 3.505 kb 35584 kps 10150.8 orig-kb 35584]" +driver: finished-cmd time 6.226 dumper0 dumped centos6-43-client:/root/testfile/36mb +driver: send-cmd time 6.226 to taper: DONE worker1 01-00002 35584 +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 +driver: interface-state time 6.240 if default: free 1997952 +driver: hdisk-state time 6.240 +driver: result time 6.240 from taper: DUMPER-STATUS 01-00002 +driver: send-cmd time 6.240 to taper: DONE worker1 01-00002 35584 +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 +driver: interface-state time 6.243 if default: free 1997952 +driver: hdisk-state time 6.243 +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]" "" "" +driver: finished-cmd time 6.243 taper wrote centos6-43-client:/root/testfile/36mb +driver: send-cmd time 6.243 to taper: CLOSE-VOLUME worker1 +driver: send-cmd time 6.244 to taper: PORT-WRITE worker1 01-00003 qa-debian6-x64-anuj /root/testfile/146mb 0 20120919143530 "" "" "" 0 "" "" "" "" AMANDA +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 +driver: interface-state time 6.244 if default: free 1997952 +driver: hdisk-state time 6.244 +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 +driver: interface-state time 6.263 if default: free 1997952 +driver: hdisk-state time 6.263 +driver: result time 6.263 from taper: PORT worker1 01-00003 35040 "127.0.0.1:54910" +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 |" bsdtcp\n YES\n YES\n AMANDA\n \n zmc-show-advanced\n off\n \n \n zmc-amanda-app\n gtar\n \n \n zmc-amcheck-version\n 3.3.2\n \n \n zmc-disklist\n amstatus_test_3\n \n \n zmc-amcheck-app\n 1.23\n \n \n zmc-version\n 3\n \n \n last-modified-time\n 2012-09-19_14:29:52\n \n \n zmc-type\n unix\n \n \n last-modified-by\n admin\n \n \n zmc-amcheck-platform\n 6.0.5\n \n \n creation-time\n 2012-09-19_14:29:52\n \n \n zmc-amcheck-date\n 20120919143206\n \n \n zmc-occ\n 80901928569\n \n \n amgtar\n \n sparse\n yes\n \n \n one-file-system\n yes\n \n \n check-device\n no\n \n \n xattrs\n no\n \n \n acls\n no\n \n \n selinux\n no\n \n \n atime-preserve\n no\n \n \n tar-blocksize\n 128\n \n \n" +taper: status file qa-debian6-x64-anuj /root/testfile/146mb:/tmp/amanda/taper_status_file_R4zzdU +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 +driver: interface-state time 10.772 if default: free 1997952 +driver: hdisk-state time 10.772 +driver: result time 10.772 from taper: REQUEST-NEW-TAPE 01-00003 +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 +driver: interface-state time 42.899 if default: free 1997952 +driver: hdisk-state time 42.899 +driver: result time 42.900 from dumper1: DONE 00-00001 715072 715072 40 "[sec 40.387 kb 715072 kps 17705.5 orig-kb 715072]" +driver: finished-cmd time 42.900 dumper1 dumped localhost:/root/testfile/732mb +driver: send-cmd time 42.900 to taper: DONE worker0 00-00001 715072 +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 +driver: interface-state time 42.903 if default: free 1997952 +driver: hdisk-state time 42.903 +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]" +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 +driver: interface-state time 42.928 if default: free 1997952 +driver: hdisk-state time 42.928 +driver: result time 42.928 from taper: DUMPER-STATUS 00-00001 +driver: send-cmd time 42.928 to taper: DONE worker0 00-00001 715072 +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 +driver: interface-state time 42.944 if default: free 1997952 +driver: hdisk-state time 42.944 +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]" "" "" +driver: finished-cmd time 42.944 taper wrote localhost:/root/testfile/732mb +driver: send-cmd time 42.944 to taper: CLOSE-VOLUME worker0 +driver: send-cmd time 42.947 to taper: TAKE-SCRIBE-FROM worker1 01-00003 worker0 +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 +driver: interface-state time 42.947 if default: free 1998976 +driver: hdisk-state time 42.947 +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 +driver: interface-state time 42.966 if default: free 1998976 +driver: hdisk-state time 42.966 +driver: result time 42.966 from taper: REQUEST-NEW-TAPE 01-00003 +driver: send-cmd time 42.966 to taper: START-SCAN worker1 01-00003 +driver: send-cmd time 42.966 to taper: NEW-TAPE worker1 01-00003 +taper: wrote label 'amstatus_test_3-AA-003' +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 +driver: interface-state time 43.011 if default: free 1998976 +driver: hdisk-state time 43.011 +driver: result time 43.011 from taper: NEW-TAPE 01-00003 amstatus_test_3-AA-003 +driver: send-cmd time 43.011 to taper: START-TAPER worker0 20120919143530 +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 +driver: interface-state time 43.064 if default: free 1998976 +driver: hdisk-state time 43.064 +driver: result time 43.064 from taper: TAPER-OK worker0 +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 +driver: interface-state time 82.686 if default: free 1998976 +driver: hdisk-state time 82.686 +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]" +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 +driver: interface-state time 82.692 if default: free 1998976 +driver: hdisk-state time 82.692 +driver: result time 82.692 from dumper0: DONE 01-00003 142336 142336 73 "[sec 72.703 kb 142336 kps 1957.8 orig-kb 142336]" +driver: finished-cmd time 82.692 dumper0 dumped qa-debian6-x64-anuj:/root/testfile/146mb +driver: send-cmd time 82.692 to taper: DONE worker1 01-00003 142336 +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 +driver: interface-state time 82.694 if default: free 1998976 +driver: hdisk-state time 82.694 +driver: result time 82.694 from taper: DONE 01-00003 INPUT-GOOD TAPE-GOOD "[sec 72.000000 bytes 145752064 kps 1976.888889]" "" "" +driver: finished-cmd time 82.694 taper wrote qa-debian6-x64-anuj:/root/testfile/146mb +driver: send-cmd time 82.694 to taper: CLOSE-VOLUME worker1 +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 +driver: interface-state time 82.695 if default: free 2000000 +driver: hdisk-state time 82.695 +driver: QUITTING time 82.695 telling children to quit +driver: send-cmd time 82.695 to dumper0: QUIT "" +driver: send-cmd time 82.695 to dumper1: QUIT "" +driver: send-cmd time 82.698 to taper: QUIT +driver: FINISHED time 83.698 +amdump: end at Wed Sep 19 14:36:53 PDT 2012