On 14.01.21 17:02, Ritesh Raj Sarraf wrote: > Hi Heinrich, > > On Thu, 2021-01-14 at 11:23 +0100, Heinrich Schuchardt wrote: >> On my system open-iscsi and libopeniscsiusr where upgraded to 2.1.3- >> 1. >> >> With a kernel installed after this upgrade my system cannot boot from >> an >> iSCSI LUN: >> >> iscsistart: version 2.1.3 >> iscsistart: Logging into iqn.2000-01.de.xypron:disk1 >> 192.168.0.1:3260,1 >> iscsistart: initiator reported error (32 - target likely not >> connected) >> >> I downgraded to open-iscsi and libopeniscsiusr to version 2.1.2-2 and >> reinstalled the kernel package. >> > > > I don't know how to react, whether I should thank you or not. > > That's the last version I had uploaded, 2.1.2. And it did clear my > tests, that of an iscsi root disk. > > Now that I know 2.1.3 has broken iscsi root setup, I will be cautious > when/if trying to debug that issue, when time permits. >
Hello Ritesh, thank you for looking into this. According to https://tracker.debian.org/pkg/open-iscsi Chris migrated 2.1.3-1 to testing. I replaced %s/iscsistart/iscsistart -d 8/g in file /usr/share/initramfs-tools/scripts/local-top/iscsi to get the extended log at the end of the mail. I hope that helps. >> Now the system boots with: >> >> iscsistart: Logging into iqn.2000-01.de.xypron:disk1 >> 192.168.0.1:3260,1 >> iscsistart: initiator reported error (15 - session exists) >> iscsistart: version 2.1.2 >> iscsistart: Logging into iqn.2000-01.de.xypron:disk1 >> 192.168.0.1:3260,1 >> iscsistart: Connection1:0 to [target: iqn.2000-01.de.xypron:disk1, >> portal: 192.168.0.1,3260] through [iface: default] is operational now > > Just for my own sake. Since the new upgrade broke your setup, how did > you revert back to the older iscsi initiator (2.1.2) for the root LUN ? > Did you build this setup on a VM ? > Did you have snapshots of the root LUN ? Only the newest initrd was concerned. So I could still boot with an old kernel after selecting it in GRUB. You can find old packages on https://snapshot.debian.org/. You could also consider chroot'ing into the image from your workstation. Best regards Heinrich Boot log ======== <snip /> Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... sh: -d: unknown operand IP-Config: eth0 hardware address xx:xx:xx:xx:xx:xx mtu 1500 DHCP[ 7.484587] dwmac-sun8i 1c30000.ethernet eth0: PHY [stmmac-0:01] driver [RTL8211E Gigabit Ethernet] (irq=POLL) RARP [ 7.502403] dwmac-sun8i 1c30000.ethernet eth0: No Safety Features support found [ 7.509764] dwmac-sun8i 1c30000.ethernet eth0: No MAC Management Counters available [ 7.517438] dwmac-sun8i 1c30000.ethernet eth0: PTP not supported by HW [ 7.524608] dwmac-sun8i 1c30000.ethernet eth0: configuring for phy/rgmii-id link mode IP-Config: eth0 guessed broadcast address 192.168.0.255 IP-Config: eth0 complete: address: 192.168.0.18 broadcast: 192.168.0.255 netmask: 255.255.255.0 gateway: 192.168.0.254 dns0 : 0.0.0.0 dns1 : 0.0.0.0 rootserver: 192.168.0.254 rootpath: filename : [ 7.570722] SCSI subsystem initialized [ 7.581238] Loading iSCSI transport class v2.0-870. [ 7.597809] iscsi: registered transport (tcp) iscsistart: in ctldev_open iscsistart: created NETLINK_ISCSI socket... iscsistart: InitiatorName=iqn.1993-08.org.debian:01:xxxxxxxxxxx iscsistart: InitiatorAlias=(none) iscsistart: TargetName=iqn.2000-01.de.xypron:disk1 iscsistart: TPGT=1 iscsistart: IP Address=192.168.0.1 iscsistart: version 2.1.3 iscsistart: current time 7 iscsistart: nothing on pend_list, deactivating alarm iscsistart: Logging into iqn.2000-01.de.xypron:disk1 192.168.0.1:3260,1 iscsistart: poll result 1 iscsistart: in read_transports iscsistart: Adding new transport tcp iscsistart: Matched transport tcp iscsistart: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'handle' iscsistart: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'caps' iscsistart: Allocted session 0xaaaafb4531d0 iscsistart: authentication setup complete... iscsistart: resolved 192.168.0.1 to 192.168.0.1 iscsistart: setting iface default, dev , set ip , hw , transport tcp. iscsistart: get ev context 0xaaaafb44b180 iscsistart: set TCP recv window size to 524288, actually got 425984 iscsistart: set TCP send window size to 524288, actually got 425984 iscsistart: connecting to 192.168.0.1:3260 iscsistart: sched conn context 0xaaaafb44b180 event 2, tmo 0 iscsistart: thread 0xaaaafb44b180 schedule: delay 0 state 3 iscsistart: Setting login timer 0xaaaafb45b138 timeout 30 iscsistart: thread aaaafb45b138 delete: state 3 iscsistart: thread 0xaaaafb45b138 schedule: delay 30 state 3 iscsistart: new thread 0xaaaafb45b138 due 38 is first item on pend_list iscsistart: new alarm set for 30 seconds, old alarm 0 iscsistart: current time 8 iscsistart: thread aaaafb45b138 due 38, wait 30 more iscsistart: exec thread aaaafb44b180 callback iscsistart: put ev context 0xaaaafb44b180 iscsistart: poll not connected 0 iscsistart: get ev context 0xaaaafb44b180 iscsistart: sched conn context 0xaaaafb44b180 event 2, tmo 1 iscsistart: thread 0xaaaafb44b180 schedule: delay 1 state 3 iscsistart: next thread 0xaaaafb45b138 due 38 iscsistart: new thread 0xaaaafb44b180 is before (9), inserting iscsistart: new alarm set for 1 seconds, old alarm 30 iscsistart: thread aaaafb44b180 done iscsistart: initiator reported error (32 - target likely not connected) iscsistart: poll result 1 iscsistart: mgmt_ipc_write_rsp: rsp to fd 8 iscsistart: in ctldev_close iscsistart: thread aaaafb44b180 delete: state 1 iscsistart: deleting a scheduled/waiting thread! iscsistart: put ev context 0xaaaafb44b180 iscsistart: Releasing session 0xaaaafb4531d0 iscsistart: iscsi child done iscsistart: in ctldev_open iscsistart: created NETLINK_ISCSI socket... iscsistart: InitiatorName=iqn.1993-08.org.debian:01:7a2b7ff63c8 iscsistart: InitiatorAlias=(none) iscsistart: TargetName=iqn.2000-01.de.xypron:disk1 iscsistart: TPGT=1 iscsistart: IP Address=192.168.0.1 iscsistart: version 2.1.3 iscsistart: current time 9 iscsistart: nothing on pend_list, deactivating alarm [ 10.604159] dwmac-sun8i 1c30000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx [ 10.612599] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready iscsistart: Logging into iqn.2000-01.de.xypron:disk1 192.168.0.1:3260,1 iscsistart: poll result 1 iscsistart: in read_transports iscsistart: Adding new transport tcp iscsistart: Matched transport tcp iscsistart: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'handle' iscsistart: sysfs_attr_get_value: open '/class/iscsi_transport/tcp'/'caps' iscsistart: Allocted session 0xaaab1046b1d0 iscsistart: authentication setup complete... iscsistart: resolved 192.168.0.1 to 192.168.0.1 iscsistart: setting iface default, dev , set ip , hw , transport tcp. iscsistart: get ev context 0xaaab10463180 iscsistart: set TCP recv window size to 524288, actually got 425984 iscsistart: set TCP send window size to 524288, actually got 425984 iscsistart: connecting to 192.168.0.1:3260 iscsistart: sched conn context 0xaaab10463180 event 2, tmo 0 iscsistart: thread 0xaaab10463180 schedule: delay 0 state 3 iscsistart: Setting login timer 0xaaab10473138 timeout 30 iscsistart: thread aaab10473138 delete: state 3 iscsistart: thread 0xaaab10473138 schedule: delay 30 state 3 iscsistart: new thread 0xaaab10473138 due 40 is first item on pend_list iscsistart: new alarm set for 30 seconds, old alarm 0 iscsistart: current time 10 iscsistart: thread aaab10473138 due 40, wait 30 more iscsistart: exec thread aaab10463180 callback iscsistart: put ev context 0xaaab10463180 iscsistart: poll not connected 0 iscsistart: get ev context 0xaaab10463180 iscsistart: sched conn context 0xaaab10463180 event 2, tmo 1 iscsistart: thread 0xaaab10463180 schedule: delay 1 state 3 iscsistart: next thread 0xaaab10473138 due 40 iscsistart: new thread 0xaaab10463180 is before (11), inserting iscsistart: new alarm set for 1 seconds, old alarm 30 iscsistart: thread aaab10463180 done iscsistart: initiator reported error (32 - target likely not connected) iscsistart: poll result 1 iscsistart: mgmt_ipc_write_rsp: rsp to fd 8 iscsistart: in ctldev_close iscsistart: thread aaab10463180 delete: state 1 iscsistart: deleting a scheduled/waiting thread! iscsistart: put ev context 0xaaab10463180 iscsistart: Releasing session 0xaaab1046b1d0 iscsistart: iscsi child done done. Begin: Running /scripts/local-premount ... [ 11.802517] Btrfs loaded, crc32c=crc32c-generic Scanning for Btrfs filesystems done. Begin: Waiting for root file system ... Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. mdadm: No arrays found in config file or automatically Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. mdadm: No arrays found in config file or automatically Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. mdadm: No arrays found in config file or automatically Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically mdadm: error opening /dev/md?*: No such file or directory mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. mdadm: No arrays found in config file or automatically [ 35.819328] vcc1v8: disabling [ 35.822359] vcc-hdmi: disabling [ 35.825580] vcc-mipi: disabling [ 35.828782] vcc-wifi: disabling [ 35.831986] vcc-1v2-hsic: disabling Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automaticallydone. mdadm: No arrays found in config file or automatically Begin: Running /scripts/local-block ... mdadm: No arrays found in config file or automatically done. done. Gave up waiting for root file system device. Common problems: - Boot args (cat /proc/cmdline) - Check rootdelay= (did the system wait long enough?) - Missing modules (cat /proc/modules; ls /dev) ALERT! UUID=xxxxxxxx-xxxx-xxxx-xxxx-xxxxxxxxxxxx does not exist. Dropping to a shell! BusyBox v1.30.1 (Debian 1:1.30.1-6) built-in shell (ash) Enter 'help' for a list of built-in commands. (initramfs)
