I/O error when accessing the tape device about mhvtl

[markh794]

Are you able to make the syslog (/var/log/messages) available - the above snippet has nothing to do with any I/O
I would also recommend turning off the mhvtl.ko logging - it's only (semi) useful if debugging the actual kernel module.
Cheers

[rkujawa]

The distribution is a Fedora 39. There's no more syslog, every message ends up in systemd-journal.

I've modified mhvtl.conf to set verbosity level to 3 and enable debug, now I get the following in journal when running mt status:

Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 254, interval: 179205
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: CDB (258) (delay 179205): 00 00 00 00 00 00
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: ssc_tur(): Test Unit Ready (258) ** : Yes
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: completeSCSICommand(): OP s/n: (258), sz: 0, sam_status: 0
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 0, interval: 5
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 0, interval: 405
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 254, interval: 805
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: CDB (259) (delay 805): 05 00 00 00 00 00
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: ssc_read_block_limits(): READ BLOCK LIMITS (259) **
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: resp_read_block_limits(): Min/Max sz: 1/2097152
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: completeSCSICommand(): OP s/n: (259), sz: 6, sam_status: 0
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 0, interval: 5
Feb 08 12:04:32 nbox.home.c0ff33.net kernel: st 7:0:1:0: [st1] Block limits 1 - 2097152 bytes.
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 254, interval: 405
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: CDB (260) (delay 405): 1a 00 00 00 0c 00
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: spc_mode_sense(): MODE SENSE 6 (260) **
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: spc_mode_sense():  Page Control     : Current values(0x00)
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: spc_mode_sense():  Page/Subpage Code: 0x00/0x00
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: spc_mode_sense():  Report Block Descriptor
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: spc_mode_sense():  Allocation len   : 12
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: mode sense: Returning 12 bytes
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: 0b 88 10 08 5e 00 00 00  00 00 00 00            : ....^... ....
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: vtltape: completeSCSICommand(): OP s/n: (260), sz: 12, sam_status: 0
Feb 08 12:04:32 nbox.home.c0ff33.net vtltape[15244]: ioctl(VX_TAPE_POLL_STATUS) returned: 0, interval: 5