I use VirtualBox to access a few iSCSI targets. Everything is working fine and the performance is acceptable. But the log file is filled with errors:
Maybe someone has come across these errors before. Note that the initiator performs a new login every second. This is certainly not supposed to happen.
Any ideas how to fix this?
Specs:
FreeNAS-9.2.1.3-RELEASE-x64
G2020, 32GiB ECC RAM, pool is healthy, cable connections are ok
Code:
Apr 3 21:10:11 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2548/2583) QCmdSN(34) error Apr 3 21:10:11 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed Apr 3 21:10:11 freenas istgt[2473]: istgt_iscsi.c:5728:worker: ***ERROR*** iscsi_execute() failed on iqn.2013-11.freenas:win-office,t,0x0001(iqn.2009-08.com.sun.virtualbox.init iator:01:10.0.50.21,i,0x80123456dc46) Apr 3 21:10:12 freenas istgt[2473]: Login from iqn.2009-08.com.sun.virtualbox.initiator:01:10.0.50.21 (10.0.50.21) on iqn.2013-11.freenas:win-office LU1 (10.0.50.15:3260,1), IS ID=80123456de2d, TSIH=2320, CID=1, HeaderDigest=off, DataDigest=off Apr 3 21:10:12 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2/38) QCmdSN(35) error Apr 3 21:10:12 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed Apr 3 21:10:12 freenas istgt[2473]: istgt_iscsi.c:5728:worker: ***ERROR*** iscsi_execute() failed on iqn.2013-11.freenas:win-office,t,0x0001(iqn.2009-08.com.sun.virtualbox.init iator:01:10.0.50.21,i,0x80123456de2d) Apr 3 21:10:13 freenas istgt[2473]: Login from iqn.2009-08.com.sun.virtualbox.initiator:01:10.0.50.21 (10.0.50.21) on iqn.2013-11.freenas:win-office LU1 (10.0.50.15:3260,1), IS ID=80123456de2e, TSIH=2321, CID=1, HeaderDigest=off, DataDigest=off Apr 3 21:10:14 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2/48) QCmdSN(45) error Apr 3 21:10:14 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed Apr 3 21:10:14 freenas istgt[2473]: istgt_iscsi.c:5728:worker: ***ERROR*** iscsi_execute() failed on iqn.2013-11.freenas:win-office,t,0x0001(iqn.2009-08.com.sun.virtualbox.init iator:01:10.0.50.21,i,0x80123456de2e) Apr 3 21:10:15 freenas istgt[2473]: Login from iqn.2009-08.com.sun.virtualbox.initiator:01:10.0.50.21 (10.0.50.21) on iqn.2013-11.freenas:win-office LU1 (10.0.50.15:3260,1), IS ID=80123456de2f, TSIH=2322, CID=1, HeaderDigest=off, DataDigest=off Apr 3 21:10:15 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2/39) QCmdSN(36) error Apr 3 21:10:15 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed Apr 3 21:10:15 freenas istgt[2473]: istgt_iscsi.c:5728:worker: ***ERROR*** iscsi_execute() failed on iqn.2013-11.freenas:win-office,t,0x0001(iqn.2009-08.com.sun.virtualbox.init iator:01:10.0.50.21,i,0x80123456de2f) Apr 3 21:10:16 freenas istgt[2473]: Login from iqn.2009-08.com.sun.virtualbox.initiator:01:10.0.50.21 (10.0.50.21) on iqn.2013-11.freenas:win-office LU1 (10.0.50.15:3260,1), IS ID=80123456de30, TSIH=2323, CID=1, HeaderDigest=off, DataDigest=off Apr 3 21:10:16 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2/45) QCmdSN(42) error Apr 3 21:10:16 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed Apr 3 21:10:16 freenas istgt[2473]: istgt_iscsi.c:5728:worker: ***ERROR*** iscsi_execute() failed on iqn.2013-11.freenas:win-office,t,0x0001(iqn.2009-08.com.sun.virtualbox.init iator:01:10.0.50.21,i,0x80123456de30) Apr 3 21:10:17 freenas istgt[2473]: Login from iqn.2009-08.com.sun.virtualbox.initiator:01:10.0.50.21 (10.0.50.21) on iqn.2013-11.freenas:win-office LU1 (10.0.50.15:3260,1), IS ID=80123456de31, TSIH=2324, CID=1, HeaderDigest=off, DataDigest=off Apr 3 21:10:17 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2/48) QCmdSN(45) error Apr 3 21:10:17 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed Apr 3 21:10:17 freenas istgt[2473]: istgt_iscsi.c:5728:worker: ***ERROR*** iscsi_execute() failed on iqn.2013-11.freenas:win-office,t,0x0001(iqn.2009-08.com.sun.virtualbox.init iator:01:10.0.50.21,i,0x80123456de31) Apr 3 21:10:18 freenas istgt[2473]: Login from iqn.2009-08.com.sun.virtualbox.initiator:01:10.0.50.21 (10.0.50.21) on iqn.2013-11.freenas:win-office LU1 (10.0.50.15:3260,1), IS ID=80123456de32, TSIH=2325, CID=1, HeaderDigest=off, DataDigest=off Apr 3 21:10:18 freenas istgt[2473]: istgt_iscsi.c:3640:istgt_iscsi_op_scsi: ***ERROR*** StatSN(2/41) QCmdSN(38) error Apr 3 21:10:18 freenas istgt[2473]: istgt_iscsi.c:5053:istgt_iscsi_execute: ***ERROR*** iscsi_op_scsi() failed
Maybe someone has come across these errors before. Note that the initiator performs a new login every second. This is certainly not supposed to happen.
Any ideas how to fix this?
Specs:
FreeNAS-9.2.1.3-RELEASE-x64
G2020, 32GiB ECC RAM, pool is healthy, cable connections are ok