* Dustin J. Mitchell <[email protected]> [20100819 18:23]: > On Thu, Aug 19, 2010 at 5:03 PM, Jon LaBadie <[email protected]> wrote: > > Note your client "gertrude". That one DLE is throwing the average to > > hell<-<-ck. > > Of your total data taped, 803,077MB, gertrude is only contributing 484MB. > > However her time to tape is 809 minutes out of 936 minutes total. So her > > 0.5% of the data taped takes 86.4% of the time to tape. > > Ah, thanks! I must have gone crosseyed trying to read that in a > proportional font :) > > Jean-Francois, can you send along the trace log and taper debug log > for this run? Assuming they're not ridiculously long, send them to > the list, not just me, so everyone can have a look.
here they come. And for good measure, I now know where the total time comes from in the taper debug file. It's from the time the scribe sets up a transfer (at 22:42:31) to the final write (12:11:42 the following day) which is 48551sec, as the DONE line in the logfile reports. You can see me smile in the mirror ;) jf > > Dustin > > -- > Open Source Storage Engineer > http://www.zmanda.com -- <° >< Jean-François Malouin McConnell Brain Imaging Centre Systems/Network Administrator Montréal Neurological Institute 3801 Rue University, Suite WB219 Montréal, Québec, H3A 2B4 Phone: 514-398-8924 Fax: 514-398-8948
INFO amdump amdump pid 9930 INFO driver driver pid 9952 INFO planner planner pid 9951 START planner date 20100818201101 DISK planner gaspar /opt/amanda DISK planner gertrude /opt/amanda DISK planner tutor /raid/bicadmin1 DISK planner tutor /raid/catlim1 DISK planner tutor /raid/data1 DISK planner tutor /raid/data2 DISK planner tutor /raid/data3 DISK planner tutor /raid/data4 DISK planner tutor /raid/data5 DISK planner tutor /raid/data6 DISK planner tutor /raid/data7 DISK planner tutor /raid/data8 DISK planner tutor /raid/proj0 DISK planner wart /opt/amanda START driver date 20100818201101 STATS driver hostname watch INFO dumper dumper pid 9954 INFO dumper dumper pid 9955 INFO dumper dumper pid 9957 INFO dumper dumper pid 9956 INFO dumper dumper pid 9960 STATS driver startup time 0.015 INFO dumper dumper pid 9959 INFO dumper dumper pid 9958 INFO dumper dumper pid 9961 INFO dumper dumper pid 9963 INFO dumper dumper pid 9964 INFO dumper dumper pid 9962 INFO dumper dumper pid 9965 INFO taper taper pid 9953 INFO planner Full dump of tutor:/raid/bicadmin1 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/catlim1 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/proj0 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/data8 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/data7 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/data6 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/data5 promoted from 5 days ahead. INFO planner Full dump of tutor:/raid/data4 promoted from 5 days ahead. FINISH planner date 20100818201101 time 9073.656 INFO planner pid-done 9951 INFO chunker chunker pid 27504 INFO chunker chunker pid 27505 INFO chunker chunker pid 27506 INFO chunker chunker pid 27507 INFO dumper gzip pid 27508 SUCCESS dumper gertrude /opt/amanda 20100818201101 0 [sec 15.879 kb 495980 kps 31234.0 orig-kb 495980] STATS driver estimate gertrude /opt/amanda 20100818201101 0 [sec 15 nkb 498012 ckb 498016 kps 31250] SUCCESS chunker gertrude /opt/amanda 20100818201101 0 [sec 15.867 kb 495980 kps 31260.2] INFO chunker pid-done 27507 SUCCESS dumper wart /opt/amanda 20100818201101 0 [sec 17.642 kb 606110 kps 34354.8 orig-kb 606110] STATS driver estimate wart /opt/amanda 20100818201101 0 [sec 17 nkb 601652 ckb 601664 kps 34475] SUCCESS chunker wart /opt/amanda 20100818201101 0 [sec 17.669 kb 606110 kps 34304.2] INFO chunker pid-done 27506 SUCCESS dumper gaspar /opt/amanda 20100818201101 0 [sec 33.821 kb 554710 kps 16401.1 orig-kb 554710] SUCCESS chunker gaspar /opt/amanda 20100818201101 0 [sec 33.950 kb 554710 kps 16339.6] INFO chunker pid-done 27505 STATS driver estimate gaspar /opt/amanda 20100818201101 0 [sec 32 nkb 553272 ckb 553280 kps 17206] SUCCESS dumper tutor /raid/catlim1 20100818201101 0 [sec 44218.964 kb 732567460 kps 16566.8 orig-kb 732567460] SUCCESS chunker tutor /raid/catlim1 20100818201101 0 [sec 44219.015 kb 732567460 kps 16566.8] INFO chunker pid-done 27504 INFO dumper pid-done 27508 STATS driver estimate tutor /raid/catlim1 20100818201101 0 [sec 31135 nkb 732569922 ckb 732569952 kps 23529] INFO chunker chunker pid 22201 SUCCESS dumper tutor /raid/bicadmin1 20100818201101 0 [sec 4334.910 kb 88126540 kps 20329.5 orig-kb 88126540] SUCCESS chunker tutor /raid/bicadmin1 20100818201101 0 [sec 4335.247 kb 88126540 kps 20327.9] INFO chunker pid-done 22201 STATS driver estimate tutor /raid/bicadmin1 20100818201101 0 [sec 4246 nkb 88094782 ckb 88094784 kps 20745] INFO chunker chunker pid 1234 INFO dumper gzip pid 1235 START taper datestamp 20100818201101 label av48-2_down_Q00048 tape 1 SUCCESS dumper tutor /raid/data2 20100818201101 1 [sec 4.339 kb 1540 kps 354.9 orig-kb 1540] INFO dumper pid-done 1235 SUCCESS chunker tutor /raid/data2 20100818201101 1 [sec 4.438 kb 1540 kps 354.2] INFO chunker pid-done 1234 STATS driver estimate tutor /raid/data2 20100818201101 1 [sec 6 nkb 1572 ckb 1600 kps 235] PART taper av48-2_down_Q00048 1 gertrude /opt/amanda 20100818201101 1/-1 0 [sec 7.303252 kb 495980 kps 67912.213876 orig-kb 495980] DONE taper gertrude /opt/amanda 20100818201101 1 0 [sec 48551.000000 kb 495980 kps 10.215650 orig-kb 495980] INFO chunker chunker pid 1246 INFO dumper gzip pid 1247 SUCCESS dumper tutor /raid/data1 20100818201101 1 [sec 3.380 kb 950 kps 281.0 orig-kb 950] INFO dumper pid-done 1247 SUCCESS chunker tutor /raid/data1 20100818201101 1 [sec 3.388 kb 950 kps 289.8] INFO chunker pid-done 1246 STATS driver estimate tutor /raid/data1 20100818201101 1 [sec 4 nkb 982 ckb 992 kps 214] INFO chunker chunker pid 1252 INFO dumper gzip pid 1253 SUCCESS dumper tutor /raid/data3 20100818201101 1 [sec 3.285 kb 1100 kps 334.8 orig-kb 1100] SUCCESS chunker tutor /raid/data3 20100818201101 1 [sec 3.294 kb 1100 kps 343.6] INFO chunker pid-done 1252 INFO dumper pid-done 1253 STATS driver estimate tutor /raid/data3 20100818201101 1 [sec 3 nkb 1132 ckb 1152 kps 321] INFO chunker chunker pid 1257 INFO dumper gzip pid 1258 SUCCESS dumper tutor /raid/proj0 20100818201101 0 [sec 0.055 kb 10 kps 180.3 orig-kb 10] INFO dumper pid-done 1258 SUCCESS chunker tutor /raid/proj0 20100818201101 0 [sec 0.055 kb 10 kps 753.6] INFO chunker pid-done 1257 STATS driver estimate tutor /raid/proj0 20100818201101 0 [sec 0 nkb 42 ckb 64 kps 1024] INFO chunker chunker pid 1262 INFO dumper gzip pid 1263 SUCCESS dumper tutor /raid/data8 20100818201101 0 [sec 0.054 kb 10 kps 182.4 orig-kb 10] INFO dumper pid-done 1263 SUCCESS chunker tutor /raid/data8 20100818201101 0 [sec 0.065 kb 10 kps 645.8] INFO chunker pid-done 1262 STATS driver estimate tutor /raid/data8 20100818201101 0 [sec 0 nkb 42 ckb 64 kps 1024] INFO chunker chunker pid 1270 INFO dumper gzip pid 1271 SUCCESS dumper tutor /raid/data7 20100818201101 0 [sec 0.055 kb 10 kps 178.9 orig-kb 10] INFO dumper pid-done 1271 SUCCESS chunker tutor /raid/data7 20100818201101 0 [sec 0.064 kb 10 kps 655.4] INFO chunker pid-done 1270 STATS driver estimate tutor /raid/data7 20100818201101 0 [sec 0 nkb 42 ckb 64 kps 1024] INFO chunker chunker pid 1273 INFO dumper gzip pid 1274 SUCCESS dumper tutor /raid/data6 20100818201101 0 [sec 0.054 kb 10 kps 183.0 orig-kb 10] INFO dumper pid-done 1274 SUCCESS chunker tutor /raid/data6 20100818201101 0 [sec 0.063 kb 10 kps 665.7] INFO chunker pid-done 1273 STATS driver estimate tutor /raid/data6 20100818201101 0 [sec 0 nkb 42 ckb 64 kps 1024] INFO chunker chunker pid 1284 INFO dumper gzip pid 1285 SUCCESS dumper tutor /raid/data5 20100818201101 0 [sec 0.056 kb 10 kps 177.1 orig-kb 10] INFO dumper pid-done 1285 SUCCESS chunker tutor /raid/data5 20100818201101 0 [sec 0.068 kb 10 kps 617.1] INFO chunker pid-done 1284 STATS driver estimate tutor /raid/data5 20100818201101 0 [sec 0 nkb 42 ckb 64 kps 1024] INFO chunker chunker pid 1290 INFO dumper gzip pid 1291 SUCCESS dumper tutor /raid/data4 20100818201101 0 [sec 0.055 kb 10 kps 180.7 orig-kb 10] INFO dumper pid-done 1291 SUCCESS chunker tutor /raid/data4 20100818201101 0 [sec 0.066 kb 10 kps 636.1] INFO chunker pid-done 1290 STATS driver estimate tutor /raid/data4 20100818201101 0 [sec 0 nkb 42 ckb 64 kps 1024] PART taper av48-2_down_Q00048 2 tutor /raid/catlim1 20100818201101 1/-1 0 [sec 437.540779 kb 41943040 kps 95860.870702 orig-kb 732567460] PART taper av48-2_down_Q00048 3 tutor /raid/catlim1 20100818201101 2/-1 0 [sec 445.453058 kb 41943040 kps 94158.159288 orig-kb 732567460] PART taper av48-2_down_Q00048 4 tutor /raid/catlim1 20100818201101 3/-1 0 [sec 378.556910 kb 41943040 kps 110797.185046 orig-kb 732567460] PART taper av48-2_down_Q00048 5 tutor /raid/catlim1 20100818201101 4/-1 0 [sec 258.049258 kb 41943040 kps 162538.890066 orig-kb 732567460] PART taper av48-2_down_Q00048 6 tutor /raid/catlim1 20100818201101 5/-1 0 [sec 372.752981 kb 41943040 kps 112522.346192 orig-kb 732567460] PART taper av48-2_down_Q00048 7 tutor /raid/catlim1 20100818201101 6/-1 0 [sec 475.690406 kb 41943040 kps 88172.978546 orig-kb 732567460] PART taper av48-2_down_Q00048 8 tutor /raid/catlim1 20100818201101 7/-1 0 [sec 376.571374 kb 41943040 kps 111381.381712 orig-kb 732567460] PART taper av48-2_down_Q00048 9 tutor /raid/catlim1 20100818201101 8/-1 0 [sec 439.058257 kb 41943040 kps 95529.555169 orig-kb 732567460] PART taper av48-2_down_Q00048 10 tutor /raid/catlim1 20100818201101 9/-1 0 [sec 302.356410 kb 41943040 kps 138720.525325 orig-kb 732567460] PART taper av48-2_down_Q00048 11 tutor /raid/catlim1 20100818201101 10/-1 0 [sec 290.769515 kb 41943040 kps 144248.409020 orig-kb 732567460] PART taper av48-2_down_Q00048 12 tutor /raid/catlim1 20100818201101 11/-1 0 [sec 273.066997 kb 41943040 kps 153599.814407 orig-kb 732567460] PART taper av48-2_down_Q00048 13 tutor /raid/catlim1 20100818201101 12/-1 0 [sec 295.687597 kb 41943040 kps 141849.169096 orig-kb 732567460] PART taper av48-2_down_Q00048 14 tutor /raid/catlim1 20100818201101 13/-1 0 [sec 312.324461 kb 41943040 kps 134293.163884 orig-kb 732567460] PART taper av48-2_down_Q00048 15 tutor /raid/catlim1 20100818201101 14/-1 0 [sec 527.265551 kb 41943040 kps 79548.227530 orig-kb 732567460] PART taper av48-2_down_Q00048 16 tutor /raid/catlim1 20100818201101 15/-1 0 [sec 535.296190 kb 41943040 kps 78354.826332 orig-kb 732567460] PART taper av48-2_down_Q00048 17 tutor /raid/catlim1 20100818201101 16/-1 0 [sec 489.994764 kb 41943040 kps 85598.955572 orig-kb 732567460] PART taper av48-2_down_Q00048 18 tutor /raid/catlim1 20100818201101 17/-1 0 [sec 385.042713 kb 41943040 kps 108930.875831 orig-kb 732567460] PART taper av48-2_down_Q00048 19 tutor /raid/catlim1 20100818201101 18/-1 0 [sec 198.363377 kb 19535780 kps 98484.812595 orig-kb 732567460] DONE taper tutor /raid/catlim1 20100818201101 18 0 [sec 6794.000000 kb 732567460 kps 107825.649102 orig-kb 732567460] PART taper av48-2_down_Q00048 20 tutor /raid/bicadmin1 20100818201101 1/-1 0 [sec 357.283012 kb 41943040 kps 117394.442388 orig-kb 88126540] PART taper av48-2_down_Q00048 21 tutor /raid/bicadmin1 20100818201101 2/-1 0 [sec 353.226218 kb 41943040 kps 118742.714540 orig-kb 88126540] PART taper av48-2_down_Q00048 22 tutor /raid/bicadmin1 20100818201101 3/-1 0 [sec 36.839058 kb 4240460 kps 115107.720040 orig-kb 88126540] DONE taper tutor /raid/bicadmin1 20100818201101 3 0 [sec 747.000000 kb 88126540 kps 117973.949130 orig-kb 88126540] PART taper av48-2_down_Q00048 23 tutor /raid/data2 20100818201101 1/-1 1 [sec 4.513279 kb 1540 kps 341.215351 orig-kb 1540] DONE taper tutor /raid/data2 20100818201101 1 1 [sec 5.000000 kb 1540 kps 308.000000 orig-kb 1540] PART taper av48-2_down_Q00048 24 tutor /raid/data3 20100818201101 1/-1 1 [sec 4.710240 kb 1100 kps 233.533769 orig-kb 1100] DONE taper tutor /raid/data3 20100818201101 1 1 [sec 4.000000 kb 1100 kps 275.000000 orig-kb 1100] PART taper av48-2_down_Q00048 25 tutor /raid/data1 20100818201101 1/-1 1 [sec 4.708013 kb 950 kps 201.783626 orig-kb 950] DONE taper tutor /raid/data1 20100818201101 1 1 [sec 5.000000 kb 950 kps 190.000000 orig-kb 950] PART taper av48-2_down_Q00048 26 tutor /raid/proj0 20100818201101 1/-1 0 [sec 4.697305 kb 10 kps 2.128880 orig-kb 10] DONE taper tutor /raid/proj0 20100818201101 1 0 [sec 5.000000 kb 10 kps 2.000000 orig-kb 10] PART taper av48-2_down_Q00048 27 tutor /raid/data8 20100818201101 1/-1 0 [sec 4.688013 kb 10 kps 2.133100 orig-kb 10] DONE taper tutor /raid/data8 20100818201101 1 0 [sec 5.000000 kb 10 kps 2.000000 orig-kb 10] PART taper av48-2_down_Q00048 28 tutor /raid/data7 20100818201101 1/-1 0 [sec 4.682466 kb 10 kps 2.135627 orig-kb 10] DONE taper tutor /raid/data7 20100818201101 1 0 [sec 4.000000 kb 10 kps 2.500000 orig-kb 10] PART taper av48-2_down_Q00048 29 tutor /raid/data6 20100818201101 1/-1 0 [sec 4.680391 kb 10 kps 2.136574 orig-kb 10] DONE taper tutor /raid/data6 20100818201101 1 0 [sec 5.000000 kb 10 kps 2.000000 orig-kb 10] PART taper av48-2_down_Q00048 30 tutor /raid/data5 20100818201101 1/-1 0 [sec 4.718052 kb 10 kps 2.119519 orig-kb 10] DONE taper tutor /raid/data5 20100818201101 1 0 [sec 5.000000 kb 10 kps 2.000000 orig-kb 10] PART taper av48-2_down_Q00048 31 tutor /raid/data4 20100818201101 1/-1 0 [sec 4.727913 kb 10 kps 2.115098 orig-kb 10] DONE taper tutor /raid/data4 20100818201101 1 0 [sec 4.000000 kb 10 kps 2.500000 orig-kb 10] PART taper av48-2_down_Q00048 32 gaspar /opt/amanda 20100818201101 1/-1 0 [sec 9.404347 kb 554710 kps 58984.426505 orig-kb 554710] DONE taper gaspar /opt/amanda 20100818201101 1 0 [sec 10.000000 kb 554710 kps 55471.000000 orig-kb 554710] PART taper av48-2_down_Q00048 33 wart /opt/amanda 20100818201101 1/-1 0 [sec 11.088528 kb 606110 kps 54660.995782 orig-kb 606110] DONE taper wart /opt/amanda 20100818201101 1 0 [sec 11.000000 kb 606110 kps 55100.909091 orig-kb 606110] INFO dumper pid-done 9954 INFO dumper pid-done 9957 INFO dumper pid-done 9956 INFO dumper pid-done 9955 INFO dumper pid-done 9958 INFO dumper pid-done 9959 INFO dumper pid-done 9961 INFO dumper pid-done 9964 INFO dumper pid-done 9963 INFO dumper pid-done 9965 INFO dumper pid-done 9960 INFO dumper pid-done 9962 INFO taper tape av48-2_down_Q00048 kb 822354450 fm 33 [OK] INFO taper pid-done 9953 FINISH driver date 20100818201101 time 65421.394 INFO driver pid-done 9952
Wed Aug 18 20:11:01 2010: taper: pid 9953 ruid 110 euid 110 version 3.1.1: start at Wed Aug 18 20:11:01 2010 Wed Aug 18 20:11:01 2010: taper: pid 9953 ruid 110 euid 110 version 3.1.1: rename at Wed Aug 18 20:11:01 2010 Wed Aug 18 20:11:01 2010: taper: chg-robot: using statefile '/opt/amanda/etc/amanda/av48-state' Wed Aug 18 20:11:01 2010: taper: Amanda::Taper::Scan::traditional stage 1: search for oldest reusable volume Wed Aug 18 20:11:01 2010: taper: Amanda::Taper::Scan::traditional oldest reusable volume is 'av48-2_down_Q00048' Wed Aug 18 20:11:01 2010: taper: Amanda::Taper::Scan::traditional stage 1: searching oldest reusable volume 'av48-2_down_Q00048' Wed Aug 18 20:11:01 2010: taper: invoking /usr/sbin/mtx -f /dev/changer status Wed Aug 18 20:11:03 2010: taper: av48: updating state Wed Aug 18 20:11:03 2010: taper: av48: loading label 'av48-2_down_Q00048' Wed Aug 18 20:11:03 2010: taper: av48: using drive 1 Wed Aug 18 20:11:03 2010: taper: invoking /usr/sbin/mtx -f /dev/changer load 38 1 Wed Aug 18 20:12:07 2010: taper: av48: polling 'tape:/dev/nst1' to see if it's ready Wed Aug 18 20:12:12 2010: taper: Amanda::Taper::Scan::traditional result: 'av48-2_down_Q00048' on tape:/dev/nst1 slot 38, mode 2 Wed Aug 18 22:42:31 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method none Wed Aug 18 22:42:31 2010: taper: Starting <x...@0x11c1fe0 (<xfersourcehold...@0x13bd010> -> <xferdesttapersplit...@0x13650a0>)> Wed Aug 18 22:42:31 2010: taper: Final linkage: <xfersourcehold...@0x13bd010> -(PULL_BUFFER)-> <xferelementg...@0x137f8e0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13650a0> Thu Aug 19 12:11:29 2010: taper: Building type TAPESTART header of 2097152-2097152 bytes with name='av48-2_down_Q00048' disk='' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:11:33 2010: taper: invoking /usr/sbin/mtx -f /dev/changer status Thu Aug 19 12:11:34 2010: taper: av48: updating state Thu Aug 19 12:11:34 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='gertrude' disk='/opt/amanda' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:11:42 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 12:11:42 2010: taper: Starting <x...@0x139b7b0 (<xfersourcehold...@0x13bd090> -> <xferdesttapersplit...@0x1365220>)> Thu Aug 19 12:11:42 2010: taper: Final linkage: <xfersourcehold...@0x13bd090> -(PULL_BUFFER)-> <xferelementg...@0x137f9b0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365220> Thu Aug 19 12:11:42 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:18:59 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:26:25 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:32:43 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:37:01 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:43:14 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:51:10 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 12:57:26 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:04:46 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:09:48 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:14:39 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:19:12 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:24:07 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:29:20 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:38:07 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:47:02 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 13:55:12 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:01:37 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/catlim1' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:04:57 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:04:57 2010: taper: Starting <x...@0x1812530 (<xfersourcehold...@0x13bd110> -> <xferdesttapersplit...@0x13653a0>)> Thu Aug 19 14:04:57 2010: taper: Final linkage: <xfersourcehold...@0x13bd110> -(PULL_BUFFER)-> <xferelementg...@0x137fa80> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13653a0> Thu Aug 19 14:04:57 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/bicadmin1' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:10:54 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/bicadmin1' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:16:47 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/bicadmin1' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:17:25 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:25 2010: taper: Starting <x...@0x1364340 (<xfersourcehold...@0x13bd190> -> <xferdesttapersplit...@0x1365520>)> Thu Aug 19 14:17:25 2010: taper: Final linkage: <xfersourcehold...@0x13bd190> -(PULL_BUFFER)-> <xferelementg...@0x137fb50> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365520> Thu Aug 19 14:17:25 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data2' dumplevel=1 and blocksize=2097152 Thu Aug 19 14:17:30 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:30 2010: taper: Starting <x...@0x1812530 (<xfersourcehold...@0x13bd210> -> <xferdesttapersplit...@0x1365520>)> Thu Aug 19 14:17:30 2010: taper: Final linkage: <xfersourcehold...@0x13bd210> -(PULL_BUFFER)-> <xferelementg...@0x137fc20> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365520> Thu Aug 19 14:17:30 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data3' dumplevel=1 and blocksize=2097152 Thu Aug 19 14:17:34 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:34 2010: taper: Starting <x...@0x13bb420 (<xfersourcehold...@0x13bd290> -> <xferdesttapersplit...@0x13653a0>)> Thu Aug 19 14:17:34 2010: taper: Final linkage: <xfersourcehold...@0x13bd290> -(PULL_BUFFER)-> <xferelementg...@0x137fcf0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13653a0> Thu Aug 19 14:17:34 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data1' dumplevel=1 and blocksize=2097152 Thu Aug 19 14:17:39 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:39 2010: taper: Starting <x...@0x180aba0 (<xfersourcehold...@0x13bd310> -> <xferdesttapersplit...@0x1365220>)> Thu Aug 19 14:17:39 2010: taper: Final linkage: <xfersourcehold...@0x13bd310> -(PULL_BUFFER)-> <xferelementg...@0x137fcf0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365220> Thu Aug 19 14:17:39 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/proj0' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:17:44 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:44 2010: taper: Starting <x...@0x180d930 (<xfersourcehold...@0x13bd390> -> <xferdesttapersplit...@0x13650a0>)> Thu Aug 19 14:17:44 2010: taper: Final linkage: <xfersourcehold...@0x13bd390> -(PULL_BUFFER)-> <xferelementg...@0x137fc20> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13650a0> Thu Aug 19 14:17:44 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data8' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:17:49 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:49 2010: taper: Starting <x...@0x13958c0 (<xfersourcehold...@0x13bd410> -> <xferdesttapersplit...@0x13650a0>)> Thu Aug 19 14:17:49 2010: taper: Final linkage: <xfersourcehold...@0x13bd410> -(PULL_BUFFER)-> <xferelementg...@0x137fb50> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13650a0> Thu Aug 19 14:17:49 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data7' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:17:53 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:53 2010: taper: Starting <x...@0x1815d20 (<xfersourcehold...@0x13bd490> -> <xferdesttapersplit...@0x1365220>)> Thu Aug 19 14:17:53 2010: taper: Final linkage: <xfersourcehold...@0x13bd490> -(PULL_BUFFER)-> <xferelementg...@0x137fa80> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365220> Thu Aug 19 14:17:53 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data6' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:17:58 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:17:58 2010: taper: Starting <x...@0x1812530 (<xfersourcehold...@0x13bd510> -> <xferdesttapersplit...@0x13653a0>)> Thu Aug 19 14:17:58 2010: taper: Final linkage: <xfersourcehold...@0x13bd510> -(PULL_BUFFER)-> <xferelementg...@0x137f9b0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13653a0> Thu Aug 19 14:17:58 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data5' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:18:03 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method cache_inform Thu Aug 19 14:18:03 2010: taper: Starting <x...@0x631700 (<xfersourcehold...@0x13bd590> -> <xferdesttapersplit...@0x1365520>)> Thu Aug 19 14:18:03 2010: taper: Final linkage: <xfersourcehold...@0x13bd590> -(PULL_BUFFER)-> <xferelementg...@0x137f8e0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365520> Thu Aug 19 14:18:03 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='tutor' disk='/raid/data4' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:18:07 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method none Thu Aug 19 14:18:07 2010: taper: Starting <x...@0x6315b0 (<xfersourcehold...@0x13bd590> -> <xferdesttapersplit...@0x1365520>)> Thu Aug 19 14:18:07 2010: taper: Final linkage: <xfersourcehold...@0x13bd590> -(PULL_BUFFER)-> <xferelementg...@0x137f8e0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x1365520> Thu Aug 19 14:18:07 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='gaspar' disk='/opt/amanda' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:18:17 2010: taper: Amanda::Taper::Scribe setting up a transfer with split method none Thu Aug 19 14:18:17 2010: taper: Starting <x...@0x13c2db0 (<xfersourcehold...@0x13bd510> -> <xferdesttapersplit...@0x13653a0>)> Thu Aug 19 14:18:17 2010: taper: Final linkage: <xfersourcehold...@0x13bd510> -(PULL_BUFFER)-> <xferelementg...@0x137f9b0> -(PUSH_BUFFER)-> <xferdesttapersplit...@0x13653a0> Thu Aug 19 14:18:17 2010: taper: Building type SPLIT_FILE header of 2097152-2097152 bytes with name='wart' disk='/opt/amanda' dumplevel=0 and blocksize=2097152 Thu Aug 19 14:21:20 2010: taper: invoking /usr/sbin/mtx -f /dev/changer status Thu Aug 19 14:21:22 2010: taper: av48: updating state Thu Aug 19 14:21:22 2010: taper: pid 9953 finish time Thu Aug 19 14:21:22 2010
