search

TheGrandWazoo / freenas-proxmox

ZFS over iSCSI to FreeNAS API's from Proxmox VE
MIT License
237 stars 43 forks source link

iSCSI: Failed to connect to LUN : iscsi_service failed #90

Closed barthohomeautomation closed 3 years ago

barthohomeautomation commented 3 years ago

We are working on setting up a ZFS to iSCSI share on Freenas. We have the SSH keys setup up, installed the patches, and set up the zfs to iscsi share in proxmox. It appears to be connected is showing as available and reports the correct available storage. However, when we attempt to move the hard drive of a VM from the current location to the new storage, we receive the following error:

create full clone of drive scsi0 (Jarhead-Virtual:201/vm-201-disk-0.raw)
iscsiadm: No session found.
transferred: 0 bytes remaining: 33554432 bytes total: 33554432 bytes progression: 0.00 %
qemu-img: Could not open 'file.driver=iscsi,file.transport=tcp,file.initiator-name=iqn.1993-08.org.debian:01:cc69eb10e3cb,file.portal=10.0.1.4,file.target=iqn.2005-10.org.freenas.ctl:proxmox-iscsi-lab,file.lun=0,driver=raw': iSCSI: Failed to connect to LUN : iscsi_service failed with : iscsi_service_reconnect_if_loggedin. Can not reconnect right now.

TASK ERROR: storage migration failed: copy failed: command '/usr/bin/qemu-img convert -p -n -f raw --target-image-opts /mnt/pve/Jarhead-Virtual/images/201/vm-201-disk-0.raw 'file.driver=iscsi,file.transport=tcp,file.initiator-name=iqn.1993-08.org.debian:01:cc69eb10e3cb,file.portal=10.0.1.4,file.target=iqn.2005-10.org.freenas.ctl:proxmox-iscsi-lab,file.lun=0,driver=raw'' failed: exit code 1

Any insight into the cause of the problem and hopefully a resolution? We have looked all over and have not been able to find a solution.

TheGrandWazoo commented 3 years ago

Are you using the iSCSI or ZFS over iSCSI storage. This look just like normal 'iSCSI' communications which is different then ZFS over iSCSI.

barthohomeautomation commented 3 years ago

I am using ZFS over iSCSI

screenshot-10-0-1-10-8006-1610466848652

TheGrandWazoo commented 3 years ago

Thanks for the clarification.

Could you paste the syslog information from the original post above or just try the procedure again and get the syslog info. A lot of information is posted to syslog so we can see where the problem could be.

barthohomeautomation commented 3 years ago

Below is what was in the syslog

Jan 12 10:24:07 proxmox pvedaemon[2302]: <michael@pam> move disk VM 201: move --disk scsi0 --storage Jarhead-VM-Lab
Jan 12 10:24:07 proxmox pvedaemon[2302]: <michael@pam> starting task UPID:proxmox:00001E91:017812E3:5FFDCD27:qmmove:201:michael@pam:
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : create_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_connect : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_connect : REST connection successful to '10.0.1.4' using the 'http' protocol
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : successful : Server version: FreeNAS-11.3-U5 (2e4ded5a0a)
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : FreeNAS Unformatted Version: 11030500
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : Using FreeNAS API version v2.0
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_globalconfiguration : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_globalconfiguration : target_basename=iqn.2005-10.org.freenas.ctl
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_create_lu : called with (method=create_lu; param[0]=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_first_available_lunid : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_first_available_lunid : 0
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=create_lu; result_value_type=name; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0) : name : lun not found
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_create_extent : called with (lun_path=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:09 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: FreeNAS::API::create_extent(lun_path=zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0) : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_create_target_to_extent : called with (target_id=1, extent_id=18, lun_id=0)
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_create_target_to_extent(target_id=1, extent_id=18, lun_id=0) : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: FreeNAS::create_lu(lun_path=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0, lun_id=0) : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : add_view()
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_lu; result_value_type=name; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0'
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0) 'name' found /dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_view(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_view : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_view; result_value_type=lun-id; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:10 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0) 'lun-id' found 0
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_extent(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_extent : called with (method=list_extent; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 12 10:24:11 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_extent : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0'
Jan 12 10:24:11 proxmox pvedaemon[7825]: FreeNAS::list_extent(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0): naa found 0x6589cfc000000bd5d3c1902172d9ff43
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_lu; result_value_type=name; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0) 'name' found /dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : delete_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_delete_lu : called with (method=delete_lu; param[0]=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_delete_lu : searching for 'targetextent' with (target_id=1; lun_id=0; extent_id=18)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_delete_lu : found 'targetextent'(target_id=1; lun_id=0; extent_id=18)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_remove_extent : called with (extent_id=18)
Jan 12 10:24:14 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 12 10:24:15 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 12 10:24:15 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_remove_extent(extent_id=18) : successful
Jan 12 10:24:15 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_remove_target_to_extent : called with (link_id=17)
Jan 12 10:24:15 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_remove_target_to_extent(link_id=17) : V2.0 API's so NOT Needed...successful
Jan 12 10:24:15 proxmox pvedaemon[7825]: PVE::Storage::LunCmd::FreeNAS::run_delete_lu(lun_path=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-201-disk-0) : successful
Jan 12 10:24:17 proxmox pvedaemon[7825]: storage migration failed: copy failed: command '/usr/bin/qemu-img convert -p -n -f raw --target-image-opts /mnt/pve/Jarhead-Virtual/images/201/vm-201-disk-0.raw 'file.driver=iscsi,file.transport=tcp,file.initiator-name=iqn.1993-08.org.debian:01:cc69eb10e3cb,file.portal=10.0.1.4,file.target=iqn.2005-10.org.freenas.ctl:proxmox-iscsi-lab,file.lun=0,driver=raw'' failed: exit code 1
Jan 12 10:24:17 proxmox pvedaemon[2302]: <michael@pam> end task UPID:proxmox:00001E91:017812E3:5FFDCD27:qmmove:201:michael@pam: storage migration failed: copy failed: command '/usr/bin/qemu-img convert -p -n -f raw --target-image-opts /mnt/pve/Jarhead-Virtual/images/201/vm-201-disk-0.raw 'file.driver=iscsi,file.transport=tcp,file.initiator-name=iqn.1993-08.org.debian:01:cc69eb10e3cb,file.portal=10.0.1.4,file.target=iqn.2005-10.org.freenas.ctl:proxmox-iscsi-lab,file.lun=0,driver=raw'' failed: exit code 1
barthohomeautomation commented 3 years ago

To hopefully assist in the diagnosis, I did attempt to create a new VM as well. The task for creating the VM completed without producing an error. However, when I attempt to start the VM, I received the followign error:

iscsiadm: No session found.
iscsiadm: No session found.
kvm: -drive file=iscsi://10.0.1.4/iqn.2005-10.org.freenas.ctl:proxmox-iscsi-lab/0,if=none,id=drive-scsi0,format=raw,cache=none,aio=native,detect-zeroes=on: iSCSI: Failed to connect to LUN : iscsi_service failed with : iscsi_service_reconnect_if_loggedin. Can not reconnect right now.

TASK ERROR: start failed: QEMU exited with code 1

Here is the syslog entry from creating and starting the VM:

Jan 13 07:26:09 proxmox pvedaemon[32439]: <michael@pam> starting task UPID:proxmox:00003CEF:01EB9DE4:5FFEF4F1:qmcreate:206:michael@pam:
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : create_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_connect : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_connect : REST connection successful to '10.0.1.4' using the 'http' protocol
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : successful : Server version: FreeNAS-11.3-U5 (2e4ded5a0a)
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : FreeNAS Unformatted Version: 11030500
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : Using FreeNAS API version v2.0
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_globalconfiguration : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_globalconfiguration : target_basename=iqn.2005-10.org.freenas.ctl
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::run_create_lu : called with (method=create_lu; param[0]=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_first_available_lunid : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_first_available_lunid : 0
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=create_lu; result_value_type=name; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0) : name : lun not found
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_create_extent : called with (lun_path=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:12 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:13 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:13 proxmox pvedaemon[15599]: FreeNAS::API::create_extent(lun_path=zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0) : successful
Jan 13 07:26:13 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_create_target_to_extent : called with (target_id=1, extent_id=21, lun_id=0)
Jan 13 07:26:13 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:13 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:13 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_create_target_to_extent(target_id=1, extent_id=21, lun_id=0) : successful
Jan 13 07:26:13 proxmox pvedaemon[15599]: FreeNAS::create_lu(lun_path=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0, lun_id=0) : successful
Jan 13 07:26:13 proxmox pvedaemon[15599]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : add_view()
Jan 13 07:26:13 proxmox pvedaemon[32439]: <michael@pam> end task UPID:proxmox:00003CEF:01EB9DE4:5FFEF4F1:qmcreate:206:michael@pam: OK
Jan 13 07:26:36 proxmox pvedaemon[15693]: start VM 206: UPID:proxmox:00003D4D:01EBA834:5FFEF50C:qmstart:206:michael@pam:
Jan 13 07:26:36 proxmox pvedaemon[4037]: <michael@pam> starting task UPID:proxmox:00003D4D:01EBA834:5FFEF50C:qmstart:206:michael@pam:
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_connect : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_connect : REST connection successful to '10.0.1.4' using the 'http' protocol
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : successful : Server version: FreeNAS-11.3-U5 (2e4ded5a0a)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : FreeNAS Unformatted Version: 11030500
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_check : Using FreeNAS API version v2.0
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_globalconfiguration : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_globalconfiguration : target_basename=iqn.2005-10.org.freenas.ctl
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_lu; result_value_type=name; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0) 'name' found /dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_view(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_view : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_view; result_value_type=lun-id; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0) 'lun-id' found 0
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_extent(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_extent : called with (method=list_extent; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:36 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_extent : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0'
Jan 13 07:26:36 proxmox pvedaemon[15693]: FreeNAS::list_extent(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0): naa found 0x6589cfc00000041ee64db50e3b555ba9
Jan 13 07:26:38 proxmox pvedaemon[15698]: starting vnc proxy UPID:proxmox:00003D52:01EBA924:5FFEF50E:vncproxy:206:michael@pam:
Jan 13 07:26:38 proxmox pvedaemon[32441]: <michael@pam> starting task UPID:proxmox:00003D52:01EBA924:5FFEF50E:vncproxy:206:michael@pam:
Jan 13 07:26:39 proxmox qm[15700]: VM 206 qmp command failed - VM 206 not running
Jan 13 07:26:39 proxmox pvedaemon[15698]: Failed to run vncproxy.
Jan 13 07:26:39 proxmox pvedaemon[32441]: <michael@pam> end task UPID:proxmox:00003D52:01EBA924:5FFEF50E:vncproxy:206:michael@pam: Failed to run vncproxy.
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_lu; result_value_type=name; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0) 'name' found /dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_view(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_view : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : called with (method=list_view; result_value_type=lun-id; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0'
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_lu(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0) 'lun-id' found 0
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_lun_command : list_extent(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_extent : called with (method=list_extent; object=/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0)
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:39 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : called
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : called
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_get_targetid : successful : 1
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : called
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_target_to_extent : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : called
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : called for host '10.0.1.4'
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_api_call : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_iscsi_get_extent : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::freenas_list_lu : successful
Jan 13 07:26:40 proxmox pvedaemon[15693]: PVE::Storage::LunCmd::FreeNAS::run_list_extent : Verifing 'zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0' and '/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0'
Jan 13 07:26:40 proxmox pvedaemon[15693]: FreeNAS::list_extent(/dev/zvol/Jarhead/Proxmox-Lab/Proxmox-VirtualMachines/vm-206-disk-0): naa found 0x6589cfc00000041ee64db50e3b555ba9
Jan 13 07:26:43 proxmox systemd[1]: Started 206.scope.
Jan 13 07:26:43 proxmox systemd[1]: 206.scope: Succeeded.
Jan 13 07:26:43 proxmox pvedaemon[15693]: start failed: QEMU exited with code 1
Jan 13 07:26:43 proxmox pvedaemon[4037]: <michael@pam> end task UPID:proxmox:00003D4D:01EBA834:5FFEF50C:qmstart:206:michael@pam: start failed: QEMU exited with code 1

One other observation, I noticed when attempting to move a hard disk to the new storage, no zvol was created on FreeNas. However, when I created a new VM, a zvol was created.

One final point, the FreeNas server was originally on FreeNAS-11.3-U5 but was later upgraded to TrueNAS-12.0-U1. However, I have it currently booting to the previous install of FreeNAS-11.3-U5 in the TrueNAS-12.0-U1 environment in case this was causing the issue.

barthohomeautomation commented 3 years ago

Well, the issue seems to resolve itself. I needed to reboot the server, and rebooted back into TrueNas 12. I tried it again for fun in 12. This time it worked. Not entirely sure why.

TheGrandWazoo commented 3 years ago

Well that is strange? A reboot? This was a new install correct? You are using the no-subscription repos? Did you update the system 'apt full-upgrade' after install? I am just trying to get my head around it if the installer of the package has an issue restarting a service?

Thanks for letting me know.

barthohomeautomation commented 3 years ago

This was an existing install of Proxmox which I have been using for a year or so. I was previously just using iSCSI storage tied to FreeNas. I moved all VM's from the iSCSI storage to local storage on Proxmox, performed a fresh install of FreeNas on my FreeNas server, restored my FreeNas config and storage pools, upgraded to TrueNas 12, and then attempted to set up ZFS to iSCSI. Yes, I did perform OS updates on both machines, And I am using the so-subscription repos.

TheGrandWazoo commented 3 years ago

Closing issue due to user resolved with a reboot.