iSCSI disk lost after wake from sleep about iscsiinitiator HOT 30 CLOSED

Heinrich,

There have been a few changes to the way this is handled (well, other changes that affect this). Please try the newest .dmg under releases and let me know if you are still experiencing this problem.

from iscsiinitiator.

Hi,
I also have this problem to. I installed from sources. And also I examined the source code. I didn't find any problem. You saved active target list, unmount and logout while sleeping. And when computer wake up login to the saved targets.

While I looked at logs, I found that:
07/07/16 12:31:39.175 PM iscsid[75721]: login to failed: Operation timed out

And also an interesting log:

07/07/16 12:35:11.597 PM iscsid[75721]: discovery is taking longer than the specified discovery interval. Consider increasing discovery interval

I started tcpdump at at both side, I do not find any network problem. Also SendTargets=All command took in milliseconds.

If I killed iscsid (respawns by kernel), Login and mounting disk is performed without any error.

However second log (about discovery interval) continues.

My interval is 60 seconds.

from iscsiinitiator.

@kazimsarikaya Does this happen when the computer sleeps on its own, or if you put it to sleep (using the apple menu on the top left)?

Are you using WiFi or a wired connection? Does this occur for both connection types?

from iscsiinitiator.

@nsinenian
I am only using wired 1gbit connection.
both if auto sleep and putting to sleep causes this.
the problem is happened after wake up. sleeping unmounts and logouts disk. however after wake up it gets timeouts to login.

I found an interesting error too. If I restart my computer, this timeout error increases boot time (also for login i need to kill iscsid again.)

I think it is a kind of order service run order problem. Iscsid runs and tries before network is up, not only link (layer 1) especially routing/ip address etc (layer 3).

I looked at launchd documantation.

If your daemon depends on the network being available, this cannot be handled with dependencies because network interfaces can come and go at any time in OS X. To solve this problem, you should use the network reachability functionality or the dynamic store functionality in the System Configuration framework. This is documented in System Configuration Programming Guidelines and System Configuration Framework Reference. For more information about network reachability, see Determining Reachability and Getting Connected in System Configuration Programming Guidelines.

from iscsiinitiator.

@kazimsarikaya "I found an interesting error too. If I restart my computer, this timeout error increases boot time (also for login i need to kill iscsid again.)"

This is strange - after a restart the previous state (previous error) should not matter. Do you have auto-login enabled by any chance? That would explain things; if auto-login was enabled and the network was not available it might take longer...

"I think it is a kind of order service run order problem. Iscsid runs and tries before network is up, not only link (layer 1) especially routing/ip address etc (layer 3)."

I agree, I think this is what is happening as well.

I think that the network reachability API may solve some of these problems. I haven't experienced any of these issues on my machine and you guys are the first to report it, so will look into this and address it for the next release. Please keep in mind that the daemon does not attempt to keep alive any sessions (e.g., if network drops out, the session is gracefully dropped, and you'd have to login again). This may be something to implement in the near future. As for sleep, I assumed that the network would come up upon sleep within the timeout time of the connection (several seconds). This has always worked on my machine but is not a good assumption.

from iscsiinitiator.

@nsinenian yes i am using auto-login enabled. hence it always retry to mount disk which is my wish. Bu when network is not ready there is a big problem. in my environment my san/nas is in different network with dynamic routing. I can not use NFS every where because i need raw disks. hence i use iscsi. i do not like fc and this is not topic of this issue.

you do not need to use reachability api, you are trying only once, retry auto-login in a loop with incremented sleeps like wait 10 seconds, 30 seconds, 60 seconds, 120 seconds and go on.

I tried to implement, however, i didn't find flow chart of the functions and where i should edit.

from iscsiinitiator.

@kazimsarikaya auto-login does not "always retry to mount ...". Instead, as you say later, auto-login only logs into the target one time when the daemon starts. This is not a mistake or accident, but rather done by design. The feature you're thinking of is typically called a "persistent" target or connection in other iSCSI initiators, which maintains a connection in the event of network drops. This is not yet implemented in this initiator.

I believe that your observations regarding boot-up taking longer are related to the sleep mode issue. It seems to me that in both cases, the daemon tries to login before the network is up. In one case it is during boot, in the other case it is after recovering from sleep.

There is an efficient and appropriate way of solving all of these issues at once - both the boot-time issue and the sleep-mode issue. Polling is generally frowned upon as it blocks access to the daemon.

I'll put something together for you to test soon...

from iscsiinitiator.

Yes boot-up an sleep is causes same issue. Persistence feature is what i wanna. sorry for my mistake for auto-login. every time network returns, i call the login, hence i think auto login what i wanna.

i am waiting good news from you about persistence.

best regards

from iscsiinitiator.

@kazimsarikaya Please try the latest commit on the features/network-availability branch. There is a "persistent" option in the target configuration that is enabled by default (iscsictl list target-config ... can be used to view this). It will cause the daemon to catch dropped connections and queue logins to those targets once they are accessible again. This should also address the sleep problem - if the machine wakes up it will queue the re-login operation until the network interfaces are back up.

Please let me know how it works out!

from iscsiinitiator.

If I checkout new branch:
git checkout features/network-availability

After compile: The first fatal error:

/Users/kazim/Documents/Xcode Projects/iSCSIInitiator/Source/User/iSCSI Framework/iSCSIIORegistry.c:41:10: fatal error:
'iSCSIHBATypes.h' file not found
#include "iSCSIHBATypes.h"
^
1 error generated.

build.out.txt

from iscsiinitiator.

Sorry about that - I hadn't committed the project file; it is now committed and I'm able to download and build using ./build under Scripts. Please try again.

from iscsiinitiator.

@nsinenian Now i can build. However there is a bug that i can not modify initiator-config.

kazimmacev:Scripts kazim$ sudo iscsictl modify initiator-config -node-alias kazimmacev
Initiator settings have been updated
kazimmacev:Scripts kazim$ iscsictl list initiator-config
iqn.2015-01.com.localhost:initiator
node-alias localhost
Authentication: none
CHAP-name localhost
CHAP-secret

Also everything I write runs successfully:

kazimmacev:Scripts kazim$ sudo iscsictl modify initiator-config -i-am-bad settings
Initiator settings have been updated

Probably it is about new initiator setting. There is a parsing error.

Other commands works as expected. May be daemon can not save new settings. Persistent option?

from iscsiinitiator.

The persistence is exactly what I'm after also.
However, the change mentioned above doesn't seem to work?

Firstly, the target is logged in and working fine.

iscsictl list targets
iqn.2017-07.com.lisa:tgt2 <active, static, sid 0, tpgt 1, ösid 0x900>
10.0.0.101 <active, cid 0, port 3260, interface default>

Then goto sleep via sleep option in apple menu, wake up, reestablish network and the target shows and remains inactive.

auto-login and persistent however are both enabled.

iscsictl list target-config iqn.2017-07.com.lisa:tgt2
iqn.2017-07.com.lisa:tgt2 <active, static, sid 0, tpgt 1, tsid 0x900>
node-alias:
auto-login: enabled
persistent: yes
Configuration:
MaxConnections 1 (1)
ErrorRecoveryLevel 0 (0)
HeaderDigest (none)
DataDigest (none)
Authentication: none
CHAP-name
CHAP-secret
10.0.0.101 <inactive, port 3260, interface default>

Using the network availability branch.

from iscsiinitiator.

@kazimsarikaya FYI, you do not need to use sudo anymore (see Authorization in wiki for how to restrict access to settings), but this is not the problem. I am unable to reproduce your "modify initiator-config" problem (it works fine on my machine). I am confused as to what the problem might be. The persistent option is enabled in target-config, not initiator-config. FYI, there is in fact a "bug" where if you enter junk it will just ignore it and say "Initiator settings updated" (I just have not implemented additional input validation yet and friendly messages; this is a small item on the to-do list). So, are you telling me that the daemon does not reconnect to the target after a wake-up event?

from iscsiinitiator.

@lulunzb What is your OS X version? Are you on a wired or wireless connection when connecting to the target? Can you please look at the system logs before going to sleep and after wakeup and tell me if anything is posted after wakeup? You'd need to look in the console under "system.log" (under the files section). I am unable to reproduce this on my machine so I need your help troubleshooting.

from iscsiinitiator.

El Capitan 10.11.5
Wireless connection

I'll have to reinstall and get the log info, will do so shortly.

I could only get the target going again by explicitly logging in.

from iscsiinitiator.

Here is the system log for a restart... you'll not that it fails to login as the network is not yet available and I cannot see it retry. Most relevant time stamps.

18.05.43 - reboot initiated.
18.06:29 - iscsi login failed
18:06:56 - en0 up.

Hope it helps.

Jul 24 18:05:43 Mac-Air shutdown[836]: reboot by Owner:
Jul 24 18:05:43 Mac-Air kernel[0]: Process launchd [1] disabling system-wide I/O Throttling
Jul 24 18:05:43 Mac-Air kernel[0]: Process launchd [1] disabling system-wide CPU Throttling
Jul 24 18:05:43 Mac-Air shutdown[836]: SHUTDOWN_TIME: 1469349343 389622
Jul 24 18:05:43 Mac-Air com.apple.xpc.launchd1: System shutdown initiated by: shutdown.836<-sessionlogoutd.834<-launchd.1
Jul 24 18:06:25 localhost bootlog[0]: BOOT_TIME 1469349385 0

Jul 24 18:06:28 --- last message repeated 1 time ---
Jul 24 18:06:27 localhost kernel[0]: Longterm timer threshold: 1000 ms
Jul 24 18:06:27 localhost kernel[0]: PMAP: PCID enabled
Jul 24 18:06:27 localhost kernel[0]: PMAP: Supervisor Mode Execute Protection enabled
Jul 24 18:06:27 localhost kernel[0]: Darwin Kernel Version 15.5.0: Tue Apr 19 18:36:36 PDT 2016; root:xnu-3248.50.21~8/RELEASE_X86_64
Jul 24 18:06:27 localhost kernel[0]: vm_page_bootstrap: 836537 free pages and 203847 wired pages
Jul 24 18:06:27 localhost kernel[0]: kext submap [0x - 0x], kernel text [0x - 0x]
Jul 24 18:06:27 localhost kernel[0]: zone leak detection enabled
Jul 24 18:06:27 localhost kernel[0]: "vm_compressor_mode" is 4
Jul 24 18:06:27 localhost kernel[0]: multiq scheduler config: deep-drain 0, ceiling 47, depth limit 4, band limit 127, sanity check 0
Jul 24 18:06:27 localhost kernel[0]: standard timeslicing quantum is 10000 us
Jul 24 18:06:27 localhost kernel[0]: standard background quantum is 2500 us
Jul 24 18:06:27 localhost kernel[0]: WQ[lt_init]: init linktable with max:262144 elements (8388608 bytes)
Jul 24 18:06:27 localhost kernel[0]: WQ[wqp_init]: init prepost table with max:262144 elements (8388608 bytes)
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: Unrecognized MachService property: ResetAtClose
Jul 24 18:06:27 localhost kernel[0]: mig_table_max_displ = 16
Jul 24 18:06:27 localhost kernel[0]: TSC Deadline Timer supported and enabled
Jul 24 18:06:27 localhost kernel[0]: kdp_core zlib memory 0x7000
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=1 LocalApicId=0 Enabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=2 LocalApicId=2 Enabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=3 LocalApicId=1 Enabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=4 LocalApicId=3 Enabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=5 LocalApicId=255 Disabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=6 LocalApicId=255 Disabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=7 LocalApicId=255 Disabled
Jul 24 18:06:27 localhost kernel[0]: AppleACPICPU: ProcessorId=8 LocalApicId=255 Disabled
Jul 24 18:06:27 localhost kernel[0]: calling mpo_policy_init for TMSafetyNet
Jul 24 18:06:27 localhost kernel[0]: Security policy loaded: Safety net for Time Machine (TMSafetyNet)
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: This service is defined to be constantly running and is inherently inefficient.
Jul 24 18:06:27 localhost kernel[0]: calling mpo_policy_init for AMFI
Jul 24 18:06:27 localhost kernel[0]: Security policy loaded: Apple Mobile File Integrity (AMFI)
Jul 24 18:06:27 localhost kernel[0]: calling mpo_policy_init for Sandbox
Jul 24 18:06:27 localhost kernel[0]: Security policy loaded: Seatbelt sandbox policy (Sandbox)
Jul 24 18:06:27 localhost kernel[0]: calling mpo_policy_init for Quarantine
Jul 24 18:06:27 localhost kernel[0]: Security policy loaded: Quarantine policy (Quarantine)
Jul 24 18:06:27 localhost kernel[0]: Copyright (c) 1982, 1986, 1989, 1991, 1993
Jul 24 18:06:27 localhost kernel[0]: The Regents of the University of California. All rights reserved.
Jul 24 18:06:27 localhost kernel[0]: MAC Framework successfully initialized
Jul 24 18:06:27 localhost kernel[0]: using 16384 buffer headers and 10240 cluster IO buffer headers
Jul 24 18:06:27 localhost kernel[0]: AppleCredentialManager::start called
Jul 24 18:06:27 localhost kernel[0]: AssertMacros: tmpData (value: 0x0), file: /BuildRoot/Library/Caches/com.apple.xbs/Sources/AppleCredentialManager/AppleCredentialManager-83.40.3/AppleCredentialManager/AppleCredentialManager.cpp, line: 785
Jul 24 18:06:27 localhost kernel[0]: AppleKeyStore starting (BUILT: Apr 19 2016 21:25:04)
Jul 24 18:06:27 localhost kernel[0]: IOAPIC: Version 0x20 Vectors 64:87
Jul 24 18:06:27 localhost kernel[0]: ACPI: sleep states S3 S4 S5
Jul 24 18:06:27 localhost kernel[0]: AppleIntelCPUPowerManagement: Turbo Ratios 0035
Jul 24 18:06:27 localhost kernel[0]: AppleIntelCPUPowerManagement: (built 19:39:50 Apr 19 2016) initialization complete
Jul 24 18:06:27 localhost kernel[0]: pci (build 19:40:46 Apr 19 2016), flags 0xe3000, pfm64 (36 cpu) 0xf80000000, 0x80000000
Jul 24 18:06:27 localhost kernel[0]: [ PCI configuration begin ]
Jul 24 18:06:27 localhost kernel[0]: console relocated to 0xf80000000
Jul 24 18:06:27 localhost kernel[0]: [ PCI configuration end, bridges 10, devices 12 ]
Jul 24 18:06:27 localhost kernel[0]: 000001.634415 AppleUSBLegacyRoot@: AppleUSBLegacyRoot::init: enabling legacy matching
Jul 24 18:06:27 localhost kernel[0]: AirPort_Brcm4360::init AirPortFamily_kexts-1110.28 "AirPortFamily_kexts-1110.28" Apr 19 2016 21:16:56
Jul 24 18:06:27 localhost kernel[0]: AirPort_Brcm4331::init AirPortFamily_kexts-1110.28 "AirPortFamily_kexts-1110.28" Apr 19 2016 21:16:56
Jul 24 18:06:27 localhost kernel[0]: AppleThunderboltNHIType2::setupPowerSavings - GPE based runtime power management
Jul 24 18:06:27 localhost kernel[0]: IOThunderboltSwitch<0>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 0 unplug = 0
Jul 24 18:06:27 localhost kernel[0]: mcache: 4 CPU(s), 64 bytes CPU cache line size
Jul 24 18:06:27 localhost kernel[0]: mbinit: done [64 MB total pool size, (42/21) split]
Jul 24 18:06:27 localhost kernel[0]: rooting via boot-uuid from /chosen: 7954F6A3-59C5-32E2-A154-9E298FB4F8E4
Jul 24 18:06:27 localhost kernel[0]: Waiting on IOProviderClassIOResourcesIOResourceMatchboot-uuid-media
Jul 24 18:06:27 localhost kernel[0]: com.apple.AppleFSCompressionTypeZlib kmod start
Jul 24 18:06:27 localhost kernel[0]: com.apple.AppleFSCompressionTypeDataless kmod start
Jul 24 18:06:27 localhost kernel[0]: com.apple.AppleFSCompressionTypeZlib load succeeded
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: The HideUntilCheckIn property is an architectural performance issue. Please transition away from it.
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: The TimeOut key is no longer respected. It never did anything anyway.
Jul 24 18:06:27 localhost kernel[0]: com.apple.AppleFSCompressionTypeDataless load succeeded
Jul 24 18:06:27 localhost kernel[0]: AppleIntelCPUPowerManagementClient: ready
Jul 24 18:06:27 localhost kernel[0]: IO80211Controller::createIOReporters 0x9ab3c8db7e74d337
Jul 24 18:06:27 localhost kernel[0]: CCFlags: 0x0, CCLevel: 5 ConsoleFlags: 0x0, ConsoleLevel: -1
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: This service is defined to be constantly running and is inherently inefficient.
Jul 24 18:06:27 localhost kernel[0]: No Service found 100000224
Jul 24 18:06:27 localhost kernel[0]: configureInterests - nElements <= 0!Failed to addSubscription for group Chip subgroup Bytes Transferred driver 0x9ab3c8db7e74d337 - data underrun
Jul 24 18:06:27 localhost kernel[0]: IO80211ControllerMonitor::configureSubscriptions() failed to add subscriptionIO80211Controller::start _controller is 0x9ab3c8db7e74d337, provider is 0x9ab3c8db1f89cc37
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: The HideUntilCheckIn property is an architectural performance issue. Please transition away from it.
Jul 24 18:06:27 localhost kernel[0]: CoreStorage: fsck_cs has finished for group "3CEBDF79-048B-4815-8DDC-CFE2B37B5D30" with status 0x00
Jul 24 18:06:27 localhost kernel[0]: Got boot device = IOService:/AppleACPIPlatformExpert/PCI0@0/AppleACPIPCI/SATA@1F,2/AppleIntelPchSeriesAHCI/PRT0@0/IOAHCIDevice@0/AppleAHCIDiskDriver/IOAHCIBlockStorageDevice/IOBlockStorageDriver/APPLE SSD TS128E Media/IOGUIDPartitionScheme/Macintosh HD@2/CoreStoragePhysical/Macintosh HD/Macintosh HD
Jul 24 18:06:27 localhost kernel[0]: BSD root: disk1, major 1, minor 4
Jul 24 18:06:27 localhost kernel[0]: IOThunderboltSwitch<0>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 11 unplug = 0
Jul 24 18:06:27 localhost kernel[0]: ARPT: 2.074419: srom rev:8
Jul 24 18:06:27 localhost kernel[0]: init: error getting PHY_MODE; using MODE_UNKNOWN
Jul 24 18:06:27 localhost kernel[0]: ARPT: 2.093161: BRCM tunables:
Jul 24 18:06:27 localhost kernel[0]: ARPT: 2.093174: pullmode[1] txringsize[ 256] txsendqsize[1024] reapmin[ 32] reapcount[ 128]
Jul 24 18:06:27 localhost kernel[0]: ARPT: 2.094282: wl0: Broadcom BCM4353, vendorID[0x14e4] BAR0[0xa0400004]
Jul 24 18:06:27 localhost kernel[0]: 7.21.95.175 (r622517)
Jul 24 18:06:27 localhost kernel[0]: hfs: mounted Macintosh HD on device root_device
Jul 24 18:06:27 localhost kernel[0]: VM Swap Subsystem is ON
Jul 24 18:06:27 localhost kernel[0]: IOThunderboltSwitch<0>(0x0)::listenerCallback - Thunderbolt HPD packet for route = 0x0 port = 12 unplug = 0
Jul 24 18:06:27 localhost kernel[0]: hfs: Removed 0 orphaned / unlinked files and 1 directories
Jul 24 18:06:27 localhost kernel[0]: USBMSC Identifier (non-unique): 000000000310 0x5ac 0x8404 0x310, 2
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: This service is defined to be constantly running and is inherently inefficient.
Jul 24 18:06:27 localhost kernel[0]: IOBluetoothUSBDFU::probe
Jul 24 18:06:27 localhost kernel[0]: IOBluetoothUSBDFU::probe ProductID - 0x821F FirmwareVersion - 0x0151
Jul 24 18:06:27 localhost kernel[0]: AppleUSBMultitouchDriver::checkStatus - received Status Packet, Payload 2: device was reinitialized
Jul 24 18:06:27 localhost watchdogd[63]: [watchdog_daemon] @( wd_watchdog_open) - IOIteratorNext failed (kr=0)
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: The HideUntilCheckIn property is an architectural performance issue. Please transition away from it.
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: The HideUntilCheckIn property is an architectural performance issue. Please transition away from it.
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: ThrottleInterval set to zero. You're not that important. Ignoring.
Jul 24 18:06:27 localhost watchdogd[63]: [watchdog_daemon] @( wd_daemon_init) - could not open connection with the kernel watchdog
Jul 24 18:06:27 localhost watchdogd[63]: [watchdog_daemon] @( main) - cannot initialize the watchdog service
Jul 24 18:06:27 localhost iconservicesagent[70]: iconservicesagent launched.
Jul 24 18:06:27 localhost hidd[103]: void __IOHIDPlugInLoadBundles(): Loaded 0 HID plugins
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: ThrottleInterval set to zero. You're not that important. Ignoring.
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: The TimeOut key is no longer respected. It never did anything anyway.
Jul 24 18:06:26 localhost com.apple.xpc.launchd1: ThrottleInterval set to zero. You're not that important. Ignoring.
Jul 24 18:06:27 localhost hidd[103]: IOHIDService compatibility thread running at priority 63 and schedule 2.
Jul 24 18:06:27 localhost com.apple.SecurityServer[85]: Session 100000 created
Jul 24 18:06:27 localhost syslogd[45]: ASL Sender Statistics
Jul 24 18:06:27 localhost powerd[55]: Registering for UPS devices
Jul 24 18:06:27 localhost powerd[55]: UPSDeviceAdded. _alreadyRunningIOUPSD:0
Jul 24 18:06:27 localhost kernel[0]: IO80211Controller::dataLinkLayerAttachComplete(): adding AppleEFINVRAM notification
Jul 24 18:06:27 localhost kernel[0]: IO80211Interface::efiNVRAMPublished():
Jul 24 18:06:27 localhost kernel[0]: initting Scan Manager controller 0x9ab3c8db7e74d337 ioservice 0x9ab3c8db1f89cc37 interface 0x9ab3c8db1fa81b37 ioservce 0x9ab3c8db7e74d337
Jul 24 18:06:27 localhost kernel[0]: bpfAttach len 64 dlt 12
Jul 24 18:06:27 localhost kernel[0]: fInterfaceSnapshots is missing
Jul 24 18:06:28 --- last message repeated 9 times ---
Jul 24 18:06:27 localhost kernel[0]: Waiting for DSMOS...
Jul 24 18:06:28 localhost digest-service[141]: label: default
Jul 24 18:06:28 localhost digest-service[141]: dbname: od:/Local/Default
Jul 24 18:06:28 localhost digest-service[141]: mkey_file: /var/db/krb5kdc/m-key
Jul 24 18:06:28 localhost digest-service[141]: acl_file: /var/db/krb5kdc/kadmind.acl
Jul 24 18:06:28 localhost digest-service[141]: digest-request: uid=0
Jul 24 18:06:28 localhost kdc[94]: krb5_kdc_set_dbinfo: failed to create root node: /Local/Default
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: Service exited with abnormal code: 1
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: Service only ran for 1 seconds. Pushing respawn out by 9 seconds.
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: Service "com.apple.ManagedClient.startup" tried to hijack endpoint "com.apple.ManagedClient.agent" from owner: com.apple.ManagedClient
Jul 24 18:06:28 localhost com.apple.SecurityServer[85]: Entering service
Jul 24 18:06:28 localhost distnoted[111]: # distnote server daemon absolute time: 5.091843166 civil time: Sun Jul 24 18:06:28 2016 pid: 111 uid: 241 root: yes
Jul 24 18:06:28 localhost AirPlayXPCHelper[104]: 2016-07-24 06:06:28.260533 PM [AirPlayEndpointManagerMeta] metaManager_createAirPlayEndpointManager Using NAPS Endpoint Manager
Jul 24 18:06:28 localhost AirPlayXPCHelper[104]: 2016-07-24 06:06:28.281779 PM [WiFiManagerCore] ### Bind to fallback "awdl0" failed: -3900/0xFFFFF0C4 kA11ParamErr
Jul 24 18:06:28 localhost AirPlayXPCHelper[104]: 2016-07-24 06:06:28.282445 PM [APTransportTrafficRegistrar] APTransportTrafficRegistrar: Deregister AirPlay traffic for AWDL at MAC 00:00:00:00:00:00 with target infra non critical PeerIndication=0 err=-3900
Jul 24 18:06:28 localhost AirPlayXPCHelper[104]: 2016-07-24 06:06:28.282821 PM [AirPlay] trafficRegistrar_resetRegistrationInternal signalled -3900/0xFFFFF0C4 kA11ParamErr: APTransportTrafficRegistrar: reset registration failed
Jul 24 18:06:28 localhost hidd[103]: ____IOHIDSessionScheduleAsync_block_invoke: thread_id=0x700000081000
Jul 24 18:06:28 localhost hidd[103]: HID Session async scheduling initiated.
Jul 24 18:06:28 localhost hidd[103]: HID Session async root queue running at priority 63 and schedule 2.
Jul 24 18:06:28 localhost ifcstart[67]: CFPasteboardRef CFPasteboardCreate(CFAllocatorRef, CFStringRef) : failed to create global data
Jul 24 18:06:28 --- last message repeated 7 times ---
Jul 24 18:06:28 localhost hidd[103]: HID Session async scheduling complete.
Jul 24 18:06:28 localhost hidd[103]: Successfully opened the IOHIDSession
Jul 24 18:06:28 localhost digest-service[141]: digest-request: netr probe 0
Jul 24 18:06:28 localhost digest-service[141]: digest-request: init request
Jul 24 18:06:28 localhost blued[90]: Delete logs
Jul 24 18:06:28 localhost digest-service[141]: digest-request: init return domain: BUILTIN server: LOCALHOST indomain was:
Jul 24 18:06:28 localhost apsd[82]: CGSLookupServerRootPort: Failed to look up the port for "com.apple.windowserver.active" (1102)
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: The HideUntilCheckIn property is an architectural performance issue. Please transition away from it.
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: This key does not do anything: OnDemand
Jul 24 18:06:28 localhost mDNSResponder[96]: mDNSResponder mDNSResponder-625.50.5 (Mar 13 2016 17:24:47) starting OSXVers 15
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: This key does not do anything: OnDemand
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: The ServiceIPC key is no longer respected. Please remove it.
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: This key does not do anything: OnDemand
Jul 24 18:06:28 localhost com.apple.usbmuxd[84]: log filter changed from 4 to 6
Jul 24 18:06:28 localhost com.apple.usbmuxd[84]: usbmuxd-364 on Mar 2 2016 at 15:12:39, running 64 bit
Jul 24 18:06:28 localhost secinitd[163]: UID[0]: cache loaded: /System/Library/Caches/com.apple.app-sandbox-cache.plist
Jul 24 18:06:28 localhost secinitd[163]: ctkd[160]: unable to get root path for bundle of main executable: /System/Library/Frameworks/CryptoTokenKit.framework/ctkd
Jul 24 18:06:28 localhost UserEventAgent[46]: Failed to copy info dictionary for bundle /System/Library/UserEventPlugins/alfUIplugin.plugin
Jul 24 18:06:28 localhost UserEventAgent[46]: Captive: CNPluginHandler en0: Inactive
Jul 24 18:06:28 localhost com.apple.xpc.launchd1: The JoinExistingSession key is only available to Application services.
Jul 24 18:06:28 localhost mDNSResponder[96]: D2D_IPC: Loaded
Jul 24 18:06:28 localhost mDNSResponder[96]: D2DInitialize succeeded
Jul 24 18:06:28 localhost kernel[0]: AirPort: Link Down on en0. Reason 8 (Disassociated because station leaving).
Jul 24 18:06:28 localhost kernel[0]: Got incomplete channel sequence length 0, should be 16
Jul 24 18:06:28 --- last message repeated 1 time ---
Jul 24 18:06:28 localhost kernel[0]: en0: channel changed to 1
Jul 24 18:06:28 localhost kernel[0]: en0::IO80211Interface::postMessage bssid changed
Jul 24 18:06:28 localhost kernel[0]: bpfAttach len 94 dlt 163
Jul 24 18:06:28 localhost kernel[0]: bpfAttach len 30 dlt 105
Jul 24 18:06:28 localhost kernel[0]: bpfAttach len 52 dlt 127
Jul 24 18:06:28 localhost kernel[0]: bpfAttach len 38 dlt 192
Jul 24 18:06:28 localhost configd[54]: dhcp_arp_router: en0 SSID unavailable
Jul 24 18:06:28 localhost configd[54]: preference: no sharing preferences
Jul 24 18:06:28 localhost configd[54]: setting hostname to "Mac-MacBook-Air.local"
Jul 24 18:06:29 Mac-MacBook-Air configd[54]: network changed: DNS*
Jul 24 18:06:29 Mac-MacBook-Air networkd[176]: main networkd-583.50.1 pid 176
Jul 24 18:06:29 Mac-MacBook-Air networkd[176]: -[NETPowerManager setupPowerPolicyTable] created power policy table from defaults with 5 timeslots
Jul 24 18:06:29 Mac-MacBook-Air networkd[176]: -[NETInterfaceManager updateInterfaces] nwi_state_copy() returned NULL
Jul 24 18:06:29 Mac-MacBook-Air networkd[176]: nw_nat64_post_new_ifstate successfully changed NAT64 ifstate from 0x0 to 0x8000000000000000
Jul 24 18:06:29 Mac-MacBook-Air networkd[176]: __settings_changed_block_invoke networkd_privileged_copy_settings_async with NULL settings
Jul 24 18:06:29 --- last message repeated 1 time ---
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: Unable to bootstrap_look_up connection port 'com.apple.ak.aps' for user 0: Unknown service name
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: Attempt to set push wake topics without dark wake enabled: ()
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: Unable to bootstrap_look_up connection port 'com.apple.askpermission.aps' for user 0: Unknown service name
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: Attempt to set push wake topics without dark wake enabled: ()
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: Unable to bootstrap_look_up connection port 'com.apple.ak.aps' for user 0: Unknown service name
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: <APSConnectionServer: 0x7f97f2d1bdb0> Invalid mach port - Cleaning up this named port's topics. com.apple.ak.aps
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: Unable to bootstrap_look_up connection port 'com.apple.askpermission.aps' for user 0: Unknown service name
Jul 24 18:06:29 Mac-MacBook-Air apsd[82]: <APSConnectionServer: 0x7f97f2d1b310> Invalid mach port - Cleaning up this named port's topics. com.apple.askpermission.aps
Jul 24 18:06:29 Mac-MacBook-Air findmydeviced[180]: findmydeviced has been launched
Jul 24 18:06:29 Mac-MacBook-Air findmydeviced[180]: Checking for /private/var/db/.AppleSetupDone & waiting, if it does not exist
Jul 24 18:06:29 Mac-MacBook-Air findmydeviced[180]: Calling directory changed on startup
Jul 24 18:06:29 Mac-MacBook-Air findmydeviced[180]: Found file being watched: .AppleSetupDone
Jul 24 18:06:29 Mac-MacBook-Air findmydeviced[180]: Deinitializing watcher
Jul 24 18:06:29 Mac-MacBook-Air findmydeviced[180]: Finished waiting for buddy to complete. Buddy marker found : 1
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: in6_unlink_ifa: IPv6 address 0xbbcbd79d1842165f has no prefix
Jul 24 18:06:29 Mac-MacBook-Air systemkeychain[155]: done file: /var/run/systemkeychaincheck.done
Jul 24 18:06:29 Mac-MacBook-Air iconservicesd[69]: iconservicesd launched.
Jul 24 18:06:29 Mac-MacBook-Air iconservicesd[69]: Cache path: /Library/Caches/com.apple.iconservices.store
Jul 24 18:06:29 Mac-MacBook-Air iconservicesagent[70]: Starting service with cache path: /var/folders/zz/zyxvpxvq6csfxvn_n0000000000000/C/com.apple.iconservices
Jul 24 18:06:29 Mac-MacBook-Air wirelessproxd[80]: updateScanner - central is not powered on: 0
Jul 24 18:06:29 Mac-MacBook-Air diskmanagementstartup[61]: started Windows Installer Partition removal
Jul 24 18:06:29 Mac-MacBook-Air diskmanagementstartup[61]: Windows Installer Partition removal: No trigger, so doing nothing
Jul 24 18:06:29 Mac-MacBook-Air diskmanagementstartup[61]: finished Windows Installer Partition removal; error = 0
Jul 24 18:06:29 Mac-MacBook-Air coreduetd[73]: CoreData: Failed to load optimized model at path '/System/Library/PrivateFrameworks/CoreDuetDataModel.framework/Resources/CoreDuetDataModel-PR-19621179.omo'
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: IOGraphics flags 0x43
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: **** [IOBluetoothHostControllerUSBTransport][start] -- completed -- result = TRUE -- 0xa800 ****
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: **** [BroadcomBluetoothHostControllerUSBTransport][start] -- Completed (matched on Device) -- 0xa800 ****
Jul 24 18:06:29 Mac-MacBook-Air awdd[183]: AutoSubmitPreference is 0
Jul 24 18:06:29 Mac-MacBook-Air com.apple.kextd[48]: Cache file /System/Library/Caches/com.apple.kext.caches/Startup/KextPropertyValues_PGO_x86_64.plist.gz is out of date; not using.
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: KScsiPrt::init 1

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: KScsiPrt::probe

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: [IOBluetoothFamily][staticBluetoothTransportShowsUp] -- Received Bluetooth Controller register service notification -- 0xa800
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: [IOBluetoothFamily][start] -- completed
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: [IOBluetoothHostController][start] -- completed
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: KScsiPrt::start 1

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: LoadPortals All

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: succesfuly open file 9488
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 0 4 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 256 256 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 1024 256 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 1280 256 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 1536 256 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 4096 4 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: ReadFile 8192 1296 0
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: KernSafeSCSIEmulatorIOKitDriver mountDevice

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: KernSafeSCSIEmulator::initSocket created 3146eae8

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Socket connect error 33 6500000a 48140
Jul 24 18:06:29 Mac-MacBook-Air blued[90]: INIT -- Host controller is published
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Problem connecting to the network.

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: iscsi login 51 6500000a 3260 iqn.2017-07.com.lisa:tgt2.

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Waiting for thread exit.

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Threadid exit

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Error to logon to iSCSI disk c0000008.

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: initializedCreate emulator failed e0000001

………………………

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: [IOBluetoothHostController::setConfigState] calling registerService
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: **** [IOBluetoothFamily][ProcessBluetoothTransportShowsUpActionWL] -- calling IOBluetoothFamily's registerService() -- 0xcf40 -- 0x6800 -- 0xa800 ****
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: **** [IOBluetoothFamily][ProcessBluetoothTransportShowsUpActionWL] -- Connected to the transport successfully -- 0xcf40 -- 0x6800 -- 0xa800 ****
Jul 24 18:06:29 Mac-MacBook-Air blued[90]: [BluetoothHIDDeviceController] EventServiceConnectedCallback
Jul 24 18:06:29 Mac-MacBook-Air blued[90]: [BluetoothHIDDeviceController] EventServiceDisconnectedCallback
Jul 24 18:06:29 Mac-MacBook-Air blued[90]: [BluetoothHIDDeviceController] EventServiceConnectedCallback
Jul 24 18:06:29 Mac-MacBook-Air blued[90]: [BluetoothHIDDeviceController] EventServiceDisconnectedCallback
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: PPGTT is enabled
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Previous shutdown cause: 3
Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: DSMOS has arrived
Jul 24 18:06:29 Mac-MacBook-Air loginwindow[99]: Login Window Application Started
Jul 24 18:06:29 Mac-MacBook-Air com.apple.xpc.launchd1: The JoinExistingSession key is only available to Application services.
Jul 24 18:06:29 Mac-MacBook-Air networkd[176]: -[NETInterfaceManager updateInterfaces] nwi_state_copy() returned NULL
Jul 24 18:06:29 Mac-MacBook-Air mds[65]: (ImportServer.Normal:1808) UseBulkImporter 0 ForceBulkImporter 0
Jul 24 18:06:29 Mac-MacBook-Air locationd[87]: Logging binary sensor data to /var/folders/zz/zyxvpxvq6csfxvn_n00000sm00006d/C/locationdSensors.bin
Jul 24 18:06:29 Mac-MacBook-Air locationd[87]: Incorrect NSStringEncoding value 0x8000100 detected. Assuming NSASCIIStringEncoding. Will stop this compatiblity mapping behavior in the near future.
Jul 24 18:06:29 Mac-MacBook-Air blued[90]: hciControllerOnline; HID devices? 1
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::getScanResult: All scan results returned for 'airportd' (pid 62).
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::startScanMultiple: Scan request received from 'airportd' (pid 62) (2 SSIDs, 0 BSSIDs).
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::startScanMultiple: Initiating scan.
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::getScanResult: All scan results returned for 'airportd' (pid 62).
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::startScanMultiple: Scan request received from 'airportd' (pid 62) (2 SSIDs, 0 BSSIDs).
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::startScanMultiple: Initiating scan.
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: IO80211ScanManager::getScanResult: All scan results returned for 'airportd' (pid 62).
Jul 24 18:06:55 Mac-MacBook-Air networkd[176]: -[NETClientConnection effectiveBundleID] using process name mapspushd as bundle ID (this is expected for daemons without bundle ID
Jul 24 18:06:55 Mac-MacBook-Air networkd[176]: -[NETClientConnection effectiveBundleID] using process name akd as bundle ID (this is expected for daemons without bundle ID
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: parseRSNIE: groupCipherType = 5 pairwiseCipherType = 5 authSel = 2
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: initWithInterfaceAndIE: _myMacAddress 70:56:81:af:38:b5
Jul 24 18:06:55 Mac-MacBook-Air kernel[0]: setPMK: PMK SET!
Jul 24 18:06:56 Mac-MacBook-Air kernel[0]: AirPort: Link Up on en0

from iscsiinitiator.

Just to also add, after sleep, it takes a while for airport to rescan and connect to wireless.

from iscsiinitiator.

The output from these logs was not generated by the iSCSI initiator hosted here… are you using KernSafe?

For example:

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: iscsi login 51 6500000a 3260 iqn.2017-07.com.lisa:tgt2.

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Waiting for thread exit.

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Threadid exit

Jul 24 18:06:29 Mac-MacBook-Air kernel[0]: Error to logon to iSCSI disk c0000008.

or:

18.05.43 - reboot initiated.
18.06:29 - iscsi login failed
18:06:56 - en0 up.

On Jul 24, 2016, at 11:04 PM, lulunzb [email protected] wrote:

Just to also add, after sleep, it takes a while for airport to rescan and connect to wireless.

—
You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub #47 (comment), or mute the thread https://github.com/notifications/unsubscribe-auth/AEH_Q01A_AUc5ibPFLipvn5nbDo5m90Sks5qY37ugaJpZM4IDADr.

from iscsiinitiator.

Have used both today - let me reinstall fully and will post some new ones

from iscsiinitiator.

@lulunzb Please make sure only one is used at a time to avoid any potential conflict.

from iscsiinitiator.

@nsinenian
Now I see that:
I can configure initiator-config. there is no problem. Problem is listing initiator config. It prints the default config.

And also I have errors like that:
Hi,
Now i have recycle of error:

(com.github.iscsi-osx.iscsid[96905]) Could not find and/or execute program specified by service: 13: Permission denied: /usr/local/libexec/iscsid
(com.github.iscsi-osx.iscsid[96905]) Service setup event to handle failure and will not launch until it fires.

it is in a loop.

Hence I run uninstall script.

from iscsiinitiator.

@kazimsarikaya Looks like there's some issue building/installing on your system with the test scripts. Here's a DMG I just built with the latest commit from the network-availability branch:

iSCSI-network-availability.zip

(Unzip then open the DMG and install as usual)

from iscsiinitiator.

I have this issues as well. Can I test this for you?

from iscsiinitiator.

@jaccoh Absolutely. Test and let me know how it works out. If you can test different permutations that would be great (e.g., wired connection vs wireless connection, and forced sleep - using the Apple menu - vs. normal sleep, where the machine just goes to sleep on its own).

from iscsiinitiator.

No problem. I'll use the latest commit from that branch?

Op 10 aug. 2016 om 13:23 heeft Nareg Sinenian [email protected] het volgende geschreven:

@jaccoh Absolutely. Test and let me know how it works out. If you can test different permutations that would be great (e.g., wired connection vs wireless connection, and forced sleep - using the Apple menu - vs. normal sleep, where the machine just goes to sleep on its own).

—
You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub, or mute the thread.

from iscsiinitiator.

Use the latest commit under the develop branch. If you have problems building let me know.

On Aug 10, 2016, at 7:39 PM, jaccoh [email protected] wrote:

No problem. I'll use the latest commit from that branch?

Op 10 aug. 2016 om 13:23 heeft Nareg Sinenian [email protected] het volgende geschreven:

@jaccoh Absolutely. Test and let me know how it works out. If you can test different permutations that would be great (e.g., wired connection vs wireless connection, and forced sleep - using the Apple menu - vs. normal sleep, where the machine just goes to sleep on its own).

—
You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub, or mute the thread.

—
You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub #47 (comment), or mute the thread https://github.com/notifications/unsubscribe-auth/AEH_QyelkafT2S5hxiVPMXv1Q2LtPcY8ks5qebiLgaJpZM4IDADr.

from iscsiinitiator.

Hello Nareg,

I'm having a problem about recovery from sleep, actually more of a request. The connection to the target itself is indeed resumed, however the filesystem on the LUN is re-mounted by OSX on a mountpoint that has nothing to do with where it was mounted before the Mac went to sleep.

Do you think there is anything that can be done about this, or is it just OSX being ... well, annoying ?

More details by email if you want them, my use case may be a bit unorthodox.

from iscsiinitiator.

I believe that this is basically out of our control with the design that we
are using.

The initiator doesn't know anything about mount points, file systems, etc.
It simply relays SCSI requests between the OS and the target. It reuses all
of the SCSI code (and other high level code) so from OS X.

In the scenario you are describing, from the OS viewpoint it's equivalent
to hot-swapping a local SCSI drive, and the OS handles this the way it
wants.

On Monday, 22 August 2016, Maxime Montinet [email protected] wrote:

Hello Nareg,

I'm having a problem about recovery from sleep, actually more of a
request. The connection to the target itself is indeed resumed, however the
filesystem on the LUN is re-mounted by OSX on a mountpoint that has nothing
to do with where it was mounted before the Mac went to sleep.

Do you think there is anything that can be done about this, or is it just
OSX being ... well, annoying ?

More details by email if you want them, my use case may be a bit
unorthodox.

—
You are receiving this because you were mentioned.
Reply to this email directly, view it on GitHub
#47 (comment),
or mute the thread
https://github.com/notifications/unsubscribe-auth/AEH_Q6kcjeGHET0tgJhtSMOfz0CpjoODks5qiMKNgaJpZM4IDADr
.

from iscsiinitiator.

Yes, I thought there might have been some way for the initiator to give hints to the OS regarding how the disk should be handled.

No worries, I found a workaround :)

from iscsiinitiator.