Write a block --
================
2401 18:33:55.169334866 0 btape (4795) < read res=3 data=wr. 
2402 18:33:55.169465021 0 btape (4795) > write fd=3(<f>/dev/nst9) size=64448 
2403 18:33:55.169484872 0 btape (4795) > switch next=4766 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2424 18:33:55.169620989 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2427 18:33:55.171756791 0 btape (4795) < write res=64448 data=............BB02................................................................ 
2428 18:33:55.171772271 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=22 
2429 18:33:55.171777930 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2448 18:33:55.171811524 0 btape (4795) < write res=22 data=btape: btape.c:1930-0  
2449 18:33:55.171813787 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=33 
2450 18:33:55.171816129 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2469 18:33:55.171838447 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2488 18:33:55.171850723 0 btape (4795) < write res=33 data=Wrote one record of 64412 bytes.. 
2489 18:33:55.171857267 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=22 
2490 18:33:55.171859029 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2509 18:33:55.171870807 0 btape (4795) < write res=22 data=btape: btape.c:1932-0  
2510 18:33:55.171872015 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=23 
2511 18:33:55.171873474 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2530 18:33:55.171885195 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2549 18:33:55.171895233 0 btape (4795) < write res=23 data=Wrote block to device.. 
2550 18:33:55.171897644 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=1 
2551 18:33:55.171899101 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
2570 18:33:55.171909903 0 btape (4795) < write res=1 data=* 
2571 18:33:55.171910581 0 btape (4795) > read fd=0(<f>/dev/pts/0) size=1024 
2572 18:33:55.171926293 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 


Rewind --
==========
54956 18:34:56.318610898 0 btape (4795) < read res=7 data=rewind. 
54957 18:34:56.318621927 0 btape (4795) > ioctl fd=3(<f>/dev/nst9) request=40086D01 argument=7FFEE8583790 
54958 18:34:56.318639930 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
54960 18:34:56.318672265 0 btape (4795) > switch next=2519(sshd) pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55162 18:34:56.330870618 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55164 18:34:56.330891732 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55166 18:34:56.331187562 0 btape (4795) < ioctl res=0 
55167 18:34:56.331204872 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=21 
55168 18:34:56.331209452 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55170 18:34:56.331212555 0 btape (4795) < write res=21 data=btape: btape.c:589-0  
55171 18:34:56.331213783 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=124 
55172 18:34:56.331215261 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55174 18:34:56.331217659 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55176 18:34:56.331218837 0 btape (4795) < write res=124 data=Rewound "Drive-1" (/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com. 
55177 18:34:56.331221311 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=1 
55178 18:34:56.331222581 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
55180 18:34:56.331223852 0 btape (4795) < write res=1 data=* 
55181 18:34:56.331224271 0 btape (4795) > read fd=0(<f>/dev/pts/0) size=1024 
55182 18:34:56.331225927 0 btape (4795) > switch next=2519(sshd) pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 



Read a block --
===============
87641 18:35:22.534428468 0 btape (4795) > open                                                                                                                                                                                                                                                                              [0/1862]
87642 18:35:22.534437371 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87644 18:35:22.534453335 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87646 18:35:22.534548977 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87648 18:35:22.534560249 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87650 18:35:22.534679721 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87652 18:35:22.534692549 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87655 18:35:22.534822694 0 btape (4795) < open fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) name=/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst flags=1(O_RDONLY) mode=0 
87656 18:35:22.534824683 0 btape (4795) > ioctl fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) request=40086D01 argument=7FFEE85836F0 
87657 18:35:22.534825987 0 btape (4795) < ioctl res=0 
87658 18:35:22.534827124 0 btape (4795) > ioctl fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) request=40086D01 argument=7FFEE85836E0 
87659 18:35:22.534832382 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87661 18:35:22.534852633 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87663 18:35:22.535006893 0 btape (4795) < ioctl res=0 
87664 18:35:22.535007674 0 btape (4795) > getuid 
87665 18:35:22.535008300 0 btape (4795) < getuid uid=0(root) 
87666 18:35:22.535008848 0 btape (4795) > ioctl fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) request=40086D01 argument=7FFEE85836E0 
87667 18:35:22.535010666 0 btape (4795) < ioctl res=0 
87668 18:35:22.535017340 0 btape (4795) > read fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) size=64512 
87669 18:35:22.535027973 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87671 18:35:22.535042398 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87673 18:35:22.536034848 0 btape (4795) < read res=-5(EIO) data= 
87674 18:35:22.536037308 0 btape (4795) > read fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) size=64512 
87675 18:35:22.536045039 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87677 18:35:22.536061879 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87679 18:35:22.536761234 0 btape (4795) < read res=-5(EIO) data= 
87680 18:35:22.536763437 0 btape (4795) > read fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) size=64512 
87681 18:35:22.536770458 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87683 18:35:22.536786866 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87685 18:35:22.537485610 0 btape (4795) < read res=-5(EIO) data= 
87686 18:35:22.537486802 0 btape (4795) > read fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) size=64512 
87687 18:35:22.537492962 0 btape (4795) > switch next=4817 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87689 18:35:22.537507295 0 btape (4795) > switch next=0 pgft_maj=0 pgft_min=400 vm_size=198584 vm_rss=5200 vm_swap=0 
87695 18:35:22.538213198 0 btape (4795) < read res=-5(EIO) data= 
87696 18:35:22.538216864 0 btape (4795) > ioctl fd=3(<f>/dev/tape/by-path/ip-172.22.2.250:3260-iscsi-iqn.1997-05.com.amazon:sgw-5d977234-tapedrive-01-lun-0-nst) request=80306D02 argument=7FFEE8583650 
87697 18:35:22.538218088 0 btape (4795) < ioctl res=0 
87698 18:35:22.538265071 0 btape (4795) > open 
87699 18:35:22.538269467 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale/en_US.UTF-8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87700 18:35:22.538270224 0 btape (4795) > open 
87701 18:35:22.538271398 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale/en_US.utf8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87702 18:35:22.538271930 0 btape (4795) > open 
87703 18:35:22.538273002 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale/en_US/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87704 18:35:22.538273496 0 btape (4795) > open 
87705 18:35:22.538274940 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale/en.UTF-8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87706 18:35:22.538275448 0 btape (4795) > open 
87707 18:35:22.538276681 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale/en.utf8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87708 18:35:22.538277156 0 btape (4795) > open 
87709 18:35:22.538278754 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale/en/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87710 18:35:22.538279240 0 btape (4795) > open 
87711 18:35:22.538280362 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale-langpack/en_US.UTF-8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87712 18:35:22.538280902 0 btape (4795) > open 
87713 18:35:22.538282066 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale-langpack/en_US.utf8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87714 18:35:22.538282710 0 btape (4795) > open 
87715 18:35:22.538283651 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale-langpack/en_US/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87716 18:35:22.538284160 0 btape (4795) > open 
87717 18:35:22.538285020 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale-langpack/en.UTF-8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87718 18:35:22.538285528 0 btape (4795) > open 
87719 18:35:22.538286306 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale-langpack/en.utf8/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87720 18:35:22.538286818 0 btape (4795) > open 
87721 18:35:22.538288205 0 btape (4795) < open fd=-2(ENOENT) name=/usr/share/locale-langpack/en/LC_MESSAGES/libc.mo flags=1(O_RDONLY) mode=0 
87722 18:35:22.538312526 0 btape (4795) > open 
87723 18:35:22.538315812 0 btape (4795) < open fd=4(<f>/etc/localtime) name=/etc/localtime flags=4097(O_RDONLY|O_CLOEXEC) mode=0 
87724 18:35:22.538316701 0 btape (4795) > fstat fd=4(<f>/etc/localtime) 
87725 18:35:22.538318400 0 btape (4795) < fstat res=0 
87726 18:35:22.538319545 0 btape (4795) > fstat fd=4(<f>/etc/localtime) 
87727 18:35:22.538319747 0 btape (4795) < fstat res=0 
87728 18:35:22.538320088 0 btape (4795) > mmap addr=0 length=4096 prot=3(PROT_READ|PROT_WRITE) flags=10(MAP_PRIVATE|MAP_ANONYMOUS) fd=4294967295 offset=0 
87729 18:35:22.538325410 0 btape (4795) < mmap res=7F8E7EF58000 vm_size=198588 vm_rss=5200 vm_swap=0 
87730 18:35:22.538326317 0 btape (4795) > read fd=4(<f>/etc/localtime) size=4096 
87731 18:35:22.538333122 0 btape (4795) < read res=3519 data=TZif2..........................................p...`...p...`.e.p.....j.p.5.`.S.. 
87732 18:35:22.538334776 0 btape (4795) > lseek fd=4(<f>/etc/localtime) offset=18446744073709549364 whence=1(SEEK_CUR) 
87733 18:35:22.538335796 0 btape (4795) < lseek res=1267 
87734 18:35:22.538336288 0 btape (4795) > read fd=4(<f>/etc/localtime) size=4096 
87735 18:35:22.538336876 0 btape (4795) < read res=2252 data=TZif2...........................................^..........p.......`.......p.... 
87736 18:35:22.538340208 0 btape (4795) > close fd=4(<f>/etc/localtime) 
87737 18:35:22.538340590 0 btape (4795) < close res=0 
87738 18:35:22.538342006 0 btape (4795) > munmap addr=7F8E7EF58000 length=4096 
87739 18:35:22.538348911 0 btape (4795) < munmap res=0 vm_size=198584 vm_rss=5272 vm_swap=0 
87740 18:35:22.538360036 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=234 
87741 18:35:22.538365120 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=404 vm_size=198584 vm_rss=5272 vm_swap=0 
87743 18:35:22.538370832 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=404 vm_size=198584 vm_rss=5272 vm_swap=0 
87745 18:35:22.538372155 0 btape (4795) < write res=234 data=05-Apr 18:35 btape JobId 0: Error: block.c:1003 Read error on fd=3 at file:blk 0 
87746 18:35:22.538374705 0 btape (4795) > write fd=1(<f>/dev/pts/0) size=1 
87747 18:35:22.538376132 0 btape (4795) > switch next=2086 pgft_maj=0 pgft_min=404 vm_size=198584 vm_rss=5272 vm_swap=0 
87749 18:35:22.538377422 0 btape (4795) < write res=1 data=* 
87750 18:35:22.538377885 0 btape (4795) > read fd=0(<f>/dev/pts/0) size=1024 
87751 18:35:22.538379550 0 btape (4795) > switch next=2519(sshd) pgft_maj=0 pgft_min=404 vm_size=198584 vm_rss=5272 vm_swap=0