Binding for Apple-TV

hww3 · September 11, 2025, 5:42pm

The when an ATV thing is enabled, it starts to receive status updates from the physical device. If the update indicates that the media being played has changed, it will request the artwork for the media. On startup, the values for the various media field (title, artist, etc) are empty, so an artwork update would be requested. I don’t know if this would trigger output on the HDMI port. It doesn’t seem to on my device, though it is connected to the TV via a surround processor. There are some quirks there that are independent of the binding, such as my Sony TV changing inputs seemingly at random when it turns on. I don’t think there’s much we can do about this, except perhaps not fetching artwork as an option.

I looked back through my logs and the latest version of the binding seems to experience far fewer instances of the error messages you described: one in the last 24 hours vs every 2-10 minutes. I think a combination of the new strict session counting from TvOS 18 and some commands possibly no longer being acceptable are to blame. I don’t think it’s realistic to expect that they’ll completely go away, but it is important that things continue to work properly around them.

I have a few other changes/improvements in the works, keep monitoring this thread, expecting probably next week.

OMR · September 11, 2025, 5:59pm

Thanks.
Just to clarify. When I said TV OFF, the ATV was OFF as well.
In fact I only use the ATV power channel to turn them both OFF.
When turning ON I always use the ATV remote, but have noticed that the power channel also work.

To me, it looks like the artwork request preceded by the turn_on is requested even though the power state is OFF

OMR · September 13, 2025, 11:45am

Another issue …
Turned off via automation using the Power Status switch channel last night.
Today, after turning on using the Siri remote, channels were not updated.
ATV was listed in the periodic scan though.

After manually seting the Power Status switch to ON, updates came through.

hww3 · September 13, 2025, 3:40pm

Are you saying that you turned the device on, and started playing something and that wasn’t reflected in the channel information? Did you refresh your client application? Sometimes the link between openhab and the clients disconnects.

If you enable debug on the binding (see below) and send me the data if it happens again, I might be able to point to a problem.

log:set DEBUG org.openhab.binding.appletv

Jacek_Kaczmarczyk · September 14, 2025, 8:08am

@hww3 hi Bill, it’s much stable right now. I am using only ON/OFF power switch combined to motion sensor rule. I am turning ON the apple tv after 30 sec of constant presence detection. What i see is sometimes dont’t know when and when the apple tv is off the commands are not working. It’s not powering on i need to bring it on with a remote. The item linked to powerswitch has “ON” but it’s not true

2025-09-13 22:59:33.601 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-13 22:59:33.602 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-13 22:59:33.602 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@49df3738
2025-09-13 22:59:33.603 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-13 22:59:33.607 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 298746
2025-09-13 22:59:33.612 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-13 22:59:40.943 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-13 22:59:40.943 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-13 22:59:40.944 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-13 22:59:40.947 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 06:18:41.795 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 06:18:41.798 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 06:18:41.799 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 06:18:41.799 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 06:18:41.801 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 06:20:31.499 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 06:25:31.535 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 06:30:31.496 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 06:33:41.802 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 06:33:41.803 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 06:33:41.803 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@77110d6c
2025-09-14 06:33:41.804 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 06:33:41.810 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 324755
2025-09-14 06:33:41.812 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 06:33:50.202 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 06:33:50.203 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 06:33:50.203 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 06:33:50.206 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 06:33:50.207 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 06:33:50.207 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 06:33:50.209 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 06:45:31.502 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 06:48:50.210 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 06:48:50.211 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 06:48:50.212 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@4208853f
2025-09-14 06:48:50.212 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 06:48:50.219 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 325594
2025-09-14 06:48:50.220 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 06:48:58.668 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 06:48:58.669 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 06:48:58.669 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 06:48:58.671 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 06:48:58.672 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 06:48:58.672 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 06:48:58.674 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 06:55:31.501 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:00:00.516 [DEBUG] [time.internal.engine.DSLScriptEngine] - Script uses context 'irrigation-6'.
2025-09-14 07:00:31.554 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:03:58.675 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 07:03:58.675 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 07:03:58.676 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@729ad2d6
2025-09-14 07:03:58.676 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 07:03:58.681 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 326439
2025-09-14 07:03:58.683 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 07:04:05.751 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:04:05.751 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 07:04:05.751 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 07:04:05.754 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:04:05.754 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 07:04:05.755 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 07:04:05.756 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 07:05:31.523 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:10:31.526 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:15:31.579 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:19:05.757 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 07:19:05.758 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 07:19:05.758 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@6afe403a
2025-09-14 07:19:05.759 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 07:19:05.763 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 327159
2025-09-14 07:19:05.768 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 07:19:13.063 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:19:13.064 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 07:19:13.065 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 07:19:13.067 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:19:13.068 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 07:19:13.068 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 07:19:13.070 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 07:20:31.543 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:25:31.545 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:30:31.593 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:34:13.070 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 07:34:13.071 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 07:34:13.071 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@37f664a9
2025-09-14 07:34:13.071 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 07:34:13.076 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 327858
2025-09-14 07:34:13.078 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 07:34:20.345 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:34:20.346 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 07:34:20.346 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 07:34:20.349 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:34:20.350 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 07:34:20.350 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 07:34:20.352 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 07:35:31.627 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:40:31.560 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:45:31.539 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:49:20.353 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 07:49:20.353 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 07:49:20.354 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@16464620
2025-09-14 07:49:20.354 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 07:49:20.360 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 328560
2025-09-14 07:49:20.364 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 07:49:27.613 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:49:27.614 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 07:49:27.615 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 07:49:27.618 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 07:49:27.618 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 07:49:27.619 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 07:49:27.620 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 07:50:31.571 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 07:55:31.564 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:00:00.524 [DEBUG] [time.internal.engine.DSLScriptEngine] - Script uses context 'irrigation-6'.
2025-09-14 08:00:31.740 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:04:27.621 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 08:04:27.622 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 08:04:27.622 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@60ca9b66
2025-09-14 08:04:27.623 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 08:04:27.627 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 329250
2025-09-14 08:04:27.632 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 08:04:34.867 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:04:34.867 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 08:04:34.868 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 08:04:34.871 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:04:34.871 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 08:04:34.872 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 08:04:34.874 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 08:05:31.663 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:10:31.582 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:15:31.578 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:19:34.875 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 08:19:34.876 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 08:19:34.876 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@112d1723
2025-09-14 08:19:34.877 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 08:19:34.881 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 329925
2025-09-14 08:19:34.900 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 08:19:42.095 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:19:42.096 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 08:19:42.096 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 08:19:42.100 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:19:42.101 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 08:19:42.101 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 08:19:42.103 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 08:20:31.583 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:24:13.389 [DEBUG] [ternal.accessories.BooleanItemReader] - Unexpected item state,  returning false. Item valve4InUse, State NULL (UnDefType)
2025-09-14 08:24:13.390 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF valve4SetDuration. Returning default value 0
2025-09-14 08:24:13.392 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF irrigationSystemTotalRemaining. Returning default value 0
2025-09-14 08:24:13.393 [DEBUG] [ternal.accessories.BooleanItemReader] - Unexpected item state,  returning false. Item valve3InUse, State NULL (UnDefType)
2025-09-14 08:24:13.394 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF valve3SetDuration. Returning default value 0
2025-09-14 08:25:31.563 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:27:29.300 [WARN ] [io.openhabcloud.internal.CloudClient] - Socket.IO disconnected: ping timeout
2025-09-14 08:27:29.346 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = aa...27, base URL = http://localhost:8080)
2025-09-14 08:27:29.595 [DEBUG] [ternal.accessories.BooleanItemReader] - Unexpected item state,  returning false. Item valve4InUse, State NULL (UnDefType)
2025-09-14 08:27:29.615 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF valve4SetDuration. Returning default value 0
2025-09-14 08:27:29.630 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF irrigationSystemTotalRemaining. Returning default value 0
2025-09-14 08:27:29.638 [DEBUG] [ternal.accessories.BooleanItemReader] - Unexpected item state,  returning false. Item valve3InUse, State NULL (UnDefType)
2025-09-14 08:27:29.641 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF valve3SetDuration. Returning default value 0
2025-09-14 08:27:33.612 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = aa...27, base URL = http://localhost:8080)
2025-09-14 08:28:30.259 [INFO ] [y.internal.handler.ShellyBaseHandler] - shelly1minig3-34b7dac70f6c: INFO: New firmware available: current version: 1.6.2-, new version: 1.7.0
2025-09-14 08:30:31.637 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:34:42.107 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 08:34:42.122 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 08:34:42.128 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@73e317ae
2025-09-14 08:34:42.147 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 08:34:42.170 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 330686
2025-09-14 08:34:42.177 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 08:34:50.141 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:34:50.145 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 08:34:50.158 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 08:34:50.286 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:34:50.288 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 08:34:50.288 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 08:34:50.331 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 08:35:31.585 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:40:31.571 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:45:31.619 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:49:50.332 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 08:49:50.336 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 08:49:50.337 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@4390f01c
2025-09-14 08:49:50.340 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 08:49:50.351 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 331409
2025-09-14 08:49:50.356 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 08:49:57.897 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:49:57.898 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 08:49:57.898 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 08:49:57.905 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 08:49:57.908 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 08:49:57.910 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 08:49:57.919 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 08:50:31.634 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 08:51:01.171 [INFO ] [nternal.handler.SonoffAccountHandler] - Updating Sonoff Access Tokens
2025-09-14 08:55:31.604 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:00:00.553 [DEBUG] [time.internal.engine.DSLScriptEngine] - Script uses context 'irrigation-6'.
2025-09-14 09:00:31.629 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:04:57.920 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 09:04:57.920 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 09:04:57.921 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@7f73b85e
2025-09-14 09:04:57.921 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:04:57.928 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 332092
2025-09-14 09:04:57.929 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 09:05:05.318 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:05:05.319 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 09:05:05.319 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 09:05:05.325 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:05:05.325 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 09:05:05.326 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 09:05:05.328 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 09:05:31.599 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:10:31.593 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:12:25.768 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@19f05327' takes more than 5000ms.
2025-09-14 09:13:01.900 [WARN ] [io.openhabcloud.internal.CloudClient] - Socket.IO disconnected: ping timeout
2025-09-14 09:13:01.901 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = aa...27, base URL = http://localhost:8080)
2025-09-14 09:13:03.139 [DEBUG] [ternal.accessories.BooleanItemReader] - Unexpected item state,  returning false. Item valve4InUse, State NULL (UnDefType)
2025-09-14 09:13:03.143 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF valve4SetDuration. Returning default value 0
2025-09-14 09:13:03.144 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF irrigationSystemTotalRemaining. Returning default value 0
2025-09-14 09:13:03.146 [DEBUG] [ternal.accessories.BooleanItemReader] - Unexpected item state,  returning false. Item valve3InUse, State NULL (UnDefType)
2025-09-14 09:13:03.147 [DEBUG] [ssories.HomekitCharacteristicFactory] - Item state NULL is UNDEF valve3SetDuration. Returning default value 0
2025-09-14 09:13:03.458 [INFO ] [io.openhabcloud.internal.CloudClient] - Connected to the openHAB Cloud service (UUID = aa...27, base URL = http://localhost:8080)
2025-09-14 09:15:31.559 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:20:05.328 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 09:20:05.331 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 09:20:05.332 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@6d83513d
2025-09-14 09:20:05.333 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:20:05.339 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 332860
2025-09-14 09:20:05.341 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 09:20:13.806 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:20:13.807 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 09:20:13.807 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 09:20:13.809 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:20:13.809 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 09:20:13.810 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 09:20:13.812 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 09:20:31.562 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:30:31.571 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:35:13.812 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 09:35:13.813 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 09:35:13.814 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@101cfb6f
2025-09-14 09:35:13.814 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:35:13.821 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 333732
2025-09-14 09:35:13.826 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 09:35:22.338 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:35:22.339 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 09:35:22.340 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 09:35:22.344 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:35:22.344 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 09:35:22.345 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 09:35:22.347 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 09:40:31.565 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:50:22.347 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-14 09:50:22.348 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-14 09:50:22.349 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@13e05d97
2025-09-14 09:50:22.350 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:50:22.356 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 334642
2025-09-14 09:50:22.358 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
2025-09-14 09:50:30.857 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device My Music on raspberrypi discovered: ipAddress=192.168.0.136, deviceId=CB4AE6AD56DD6A12, loginId=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:50:30.857 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='CB4AE6AD56DD6A12', name='My Music on raspberrypi', address='192.168.0.136', services=[Service{protocol='dmap', port=3689, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Unknown', operatingSystem='Legacy', version='null', buildNumber='null', mac='null'}}
2025-09-14 09:50:30.858 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=null, loginId=My Music on raspberrypi, modelId=Unknown, vendor=Apple, ipAddress=192.168.0.136, pairingDmap=Mandatory, firmwareVersion=Legacy null, deviceId=CB4AE6AD56DD6A12}
2025-09-14 09:50:30.860 [INFO ] [ding.appletv.internal.AppleTVHandler] - Device Apple TV sypialnia discovered: ipAddress=192.168.0.52, deviceId=12:54:B9:78:AA:8B, loginId=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], services={}
2025-09-14 09:50:30.861 [INFO ] [ding.appletv.internal.AppleTVHandler] - DeviceInfo: ATVDevice{identifier='12:54:B9:78:AA:8B', name='Apple TV sypialnia', address='192.168.0.52', services=[Service{protocol='airplay', port=7000, password=false, pairing=Mandatory, enabled=true}, Service{protocol='companion', port=49153, password=false, pairing=Mandatory, enabled=true}, Service{protocol='raop', port=7000, password=false, pairing=Mandatory, enabled=true}], deviceInfo=DeviceInfo{model='Gen4K', operatingSystem='TvOS', version='18.6', buildNumber='null', mac='12:54:B9:78:AA:8B'}}
2025-09-14 09:50:30.861 [INFO ] [ding.appletv.internal.AppleTVHandler] - Properties: {macAddress=12:54:B9:78:AA:8B, loginId=Apple TV sypialnia, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, ipAddress=192.168.0.52, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-14 09:50:30.863 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery completed
2025-09-14 09:50:31.570 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:55:13.431 [WARN ] [e.internal.SseItemStatesEventBuilder] - Attempting to send a state update of an item which doesn't exist: MINI
2025-09-14 09:55:31.571 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.
2025-09-14 09:55:42.582 [INFO ] [nding.appletv.internal.PyATV$Scanner] - queueing command: turn_off
2025-09-14 09:55:42.846 [ERROR] [ding.appletv.internal.AppleTVHandler] - ATV command threw exception: Object of type BlockedStateError is not JSON serializable:
Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 483, in _run_command
    await getattr(atv.power, command)()
                  ^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/support/shield.py", line 72, in _guard_method
    raise BlockedStateError(f"{func.__name__} is blocked")
pyatv.exceptions.BlockedStateError: power is blocked

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 618, in appstart
    args.output(await _handle_command(args, abort_sem, storage, loop)),
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 359, in _handle_command
    return await _run_command(atv, args, abort_sem, loop)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 526, in _run_command
    stdout_write_nb(args.output(output(False, values={"exception": e})))
                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/__init__.py", line 231, in dumps
    return _default_encoder.encode(obj)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 200, in encode
    chunks = self.iterencode(o, _one_shot=True)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 258, in iterencode
    return _iterencode(o, 0)
           ^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 180, in default
    raise TypeError(f'Object of type {o.__class__.__name__} '
TypeError: Object of type BlockedStateError is not JSON serializable

2025-09-14 09:55:44.249 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Command process=276065 exited.
2025-09-14 09:55:44.250 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 276065 exists.
2025-09-14 09:55:44.257 [WARN ] [nding.appletv.internal.PyATV$Scanner] - PATH: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:55:44.257 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Started process 334960
2025-09-14 09:55:44.258 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 334960 isRunning=true
2025-09-14 09:55:44.258 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Running /var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 command_loop
2025-09-14 09:55:44.752 [INFO ] [nding.appletv.internal.PyATV$Scanner] - queueing command: turn_on
2025-09-14 09:55:47.532 [WARN ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Error occurred in '/var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 command_loop'
com.google.gson.JsonSyntaxException: com.google.gson.stream.MalformedJsonException: Unexpected value at line 1 column 30 path $
See https://github.com/google/gson/blob/main/Troubleshooting.md#malformed-json
        at com.google.gson.JsonStreamParser.hasNext(JsonStreamParser.java:108) ~[?:?]
        at org.openhab.binding.appletv.internal.PyATV$Scanner.executeCommandLineAndParseJson(PyATV.java:515) ~[?:?]
        at org.openhab.binding.appletv.internal.PyATV$Scanner.runReaderThread(PyATV.java:356) ~[?:?]
        at java.lang.Thread.run(Thread.java:1583) [?:?]
Caused by: com.google.gson.stream.MalformedJsonException: Unexpected value at line 1 column 30 path $
See https://github.com/google/gson/blob/main/Troubleshooting.md#malformed-json
        at com.google.gson.stream.JsonReader.syntaxError(JsonReader.java:1817) ~[?:?]
        at com.google.gson.stream.JsonReader.doPeek(JsonReader.java:696) ~[?:?]
        at com.google.gson.stream.JsonReader.peek(JsonReader.java:547) ~[?:?]
        at com.google.gson.JsonStreamParser.hasNext(JsonStreamParser.java:106) ~[?:?]
        ... 3 more
2025-09-14 09:55:47.539 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Command process=334960 exited.
2025-09-14 09:55:47.539 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 334960 exists.
2025-09-14 09:55:47.540 [INFO ] [nding.appletv.internal.PyATV$Scanner] - destroying process 334960 forcibly.
2025-09-14 09:55:47.542 [INFO ] [ding.appletv.internal.AppleTVHandler] - Received word that device went offline.
2025-09-14 09:55:47.542 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: stop()
2025-09-14 09:55:47.543 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Attempt to read command queue was interrupted.
2025-09-14 09:55:47.544 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: exiting command poller
2025-09-14 09:55:47.545 [ERROR] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Exception thrown in runWriterThread
java.lang.NullPointerException: Cannot invoke "java.lang.Process.pid()" because "this.process" is null
        at org.openhab.binding.appletv.internal.PyATV$Scanner.runWriterThread(PyATV.java:381) ~[?:?]
        at java.lang.Thread.run(Thread.java:1583) [?:?]
2025-09-14 09:55:47.548 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Scanner not running, will not attempt to stop.
2025-09-14 09:55:47.549 [INFO ] [nding.appletv.internal.PyATV$Scanner] - returning because !keepRunning
2025-09-14 09:55:47.549 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:55:47.564 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 334966
2025-09-14 09:55:47.566 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]
2025-09-14 09:55:50.965 [INFO ] [ding.appletv.internal.AppleTVHandler] - Pairing Verified: 12:54:B9:78:AA:8B

2025-09-14 09:55:50.966 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:55:50.971 [INFO ] [enhab.binding.appletv.internal.PyATV] - Started process 334972
2025-09-14 09:55:50.971 [INFO ] [enhab.binding.appletv.internal.PyATV] - Running [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, all_features]
2025-09-14 09:55:53.515 [INFO ] [ding.appletv.internal.AppleTVHandler] - Features: Features{features=[FeatureInfo{name='Up', state=Available, options={}}, FeatureInfo{name='Down', state=Available, options={}}, FeatureInfo{name='Left', state=Available, options={}}, FeatureInfo{name='Right', state=Available, options={}}, FeatureInfo{name='Play', state=Unavailable, options={}}, FeatureInfo{name='PlayPause', state=Unavailable, options={}}, FeatureInfo{name='Pause', state=Unavailable, options={}}, FeatureInfo{name='Stop', state=Unavailable, options={}}, FeatureInfo{name='Next', state=Unavailable, options={}}, FeatureInfo{name='Previous', state=Unavailable, options={}}, FeatureInfo{name='Select', state=Available, options={}}, FeatureInfo{name='Menu', state=Available, options={}}, FeatureInfo{name='VolumeUp', state=Available, options={}}, FeatureInfo{name='VolumeDown', state=Available, options={}}, FeatureInfo{name='Home', state=Available, options={}}, FeatureInfo{name='HomeHold', state=Available, options={}}, FeatureInfo{name='TopMenu', state=Available, options={}}, FeatureInfo{name='SkipForward', state=Unavailable, options={}}, FeatureInfo{name='SkipBackward', state=Unavailable, options={}}, FeatureInfo{name='SetPosition', state=Unavailable, options={}}, FeatureInfo{name='SetShuffle', state=Unavailable, options={}}, FeatureInfo{name='SetRepeat', state=Unavailable, options={}}, FeatureInfo{name='ChannelUp', state=Available, options={}}, FeatureInfo{name='ChannelDown', state=Available, options={}}, FeatureInfo{name='Title', state=Unavailable, options={}}, FeatureInfo{name='Artist', state=Unavailable, options={}}, FeatureInfo{name='Album', state=Unavailable, options={}}, FeatureInfo{name='Genre', state=Unavailable, options={}}, FeatureInfo{name='TotalTime', state=Unavailable, options={}}, FeatureInfo{name='Position', state=Unavailable, options={}}, FeatureInfo{name='Shuffle', state=Unavailable, options={}}, FeatureInfo{name='Repeat', state=Unavailable, options={}}, FeatureInfo{name='SeriesName', state=Unavailable, options={}}, FeatureInfo{name='SeasonNumber', state=Unavailable, options={}}, FeatureInfo{name='EpisodeNumber', state=Unavailable, options={}}, FeatureInfo{name='ContentIdentifier', state=Unavailable, options={}}, FeatureInfo{name='iTunesStoreIdentifier', state=Unavailable, options={}}, FeatureInfo{name='AppList', state=Available, options={}}, FeatureInfo{name='LaunchApp', state=Available, options={}}, FeatureInfo{name='AccountList', state=Available, options={}}, FeatureInfo{name='SwitchAccount', state=Available, options={}}, FeatureInfo{name='Artwork', state=Unavailable, options={}}, FeatureInfo{name='App', state=Unavailable, options={}}, FeatureInfo{name='PushUpdates', state=Available, options={}}, FeatureInfo{name='PlayUrl', state=Available, options={}}, FeatureInfo{name='StreamFile', state=Available, options={}}, FeatureInfo{name='PowerState', state=Available, options={}}, FeatureInfo{name='Screensaver', state=Available, options={}}, FeatureInfo{name='TurnOn', state=Available, options={}}, FeatureInfo{name='TurnOff', state=Available, options={}}, FeatureInfo{name='Volume', state=Unavailable, options={}}, FeatureInfo{name='SetVolume', state=Unavailable, options={}}, FeatureInfo{name='OutputDevices', state=Available, options={}}, FeatureInfo{name='AddOutputDevices', state=Available, options={}}, FeatureInfo{name='RemoveOutputDevices', state=Available, options={}}, FeatureInfo{name='SetOutputDevices', state=Available, options={}}, FeatureInfo{name='TextFocusState', state=Available, options={}}, FeatureInfo{name='TextGet', state=Available, options={}}, FeatureInfo{name='TextClear', state=Available, options={}}, FeatureInfo{name='TextAppend', state=Available, options={}}, FeatureInfo{name='TextSet', state=Available, options={}}, FeatureInfo{name='Swipe', state=Available, options={}}, FeatureInfo{name='Action', state=Available, options={}}, FeatureInfo{name='Click', state=Available, options={}}]}
2025-09-14 09:55:53.516 [INFO ] [ding.appletv.internal.AppleTVHandler] - Command Scanner: null
2025-09-14 09:55:53.517 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: start()
2025-09-14 09:55:53.517 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: starting handler threads
2025-09-14 09:55:53.518 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Scanner starting
2025-09-14 09:55:53.518 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Commander starting
2025-09-14 09:55:53.519 [INFO ] [nding.appletv.internal.PyATV$Scanner] - aieee
2025-09-14 09:55:53.519 [WARN ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop process not running. waiting 500ms. Scanner{name='AppleTVCommandLoop', process=null, isStarted=true, expectedCommandLine='null'}
2025-09-14 09:55:53.523 [WARN ] [nding.appletv.internal.PyATV$Scanner] - PATH: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 09:55:53.524 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Started process 334981
2025-09-14 09:55:53.524 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 334981 isRunning=true
2025-09-14 09:55:53.524 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Running /var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 command_loop
2025-09-14 10:00:00.520 [DEBUG] [time.internal.engine.DSLScriptEngine] - Script uses context 'irrigation-6'.
2025-09-14 10:00:31.583 [WARN ] [ding.tuya.internal.cloud.TuyaOpenAPI] - Server reported invalid token. This should never happen. Trying to re-login.

Jacek_Kaczmarczyk · September 14, 2025, 9:41am

Wold it be possible to introduce app_list command as a command my goal is to launch an app via your binding. it’s seems not working right now

2025-09-14 11:37:36.150 [ERROR] [ding.appletv.internal.AppleTVHandler] - ATV command threw exception: Object of type NameError is not JSON serializable:
Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 516, in _run_command
    stdoute_write_nb(args.output(output_apps(await getattr(atv.apps, command)())))
    ^^^^^^^^^^^^^^^^
NameError: name 'stdoute_write_nb' is not defined

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 618, in appstart
    args.output(await _handle_command(args, abort_sem, storage, loop)),
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 359, in _handle_command
    return await _run_command(atv, args, abort_sem, loop)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 526, in _run_command
    stdout_write_nb(args.output(output(False, values={"exception": e})))
                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/__init__.py", line 231, in dumps
    return _default_encoder.encode(obj)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 200, in encode
    chunks = self.iterencode(o, _one_shot=True)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 258, in iterencode
    return _iterencode(o, 0)
           ^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 180, in default
    raise TypeError(f'Object of type {o.__class__.__name__} '
TypeError: Object of type NameError is not JSON serializable

2025-09-14 11:37:36.552 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Command process=339033 exited.
2025-09-14 11:37:36.552 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 339033 exists.
2025-09-14 11:37:36.557 [WARN ] [nding.appletv.internal.PyATV$Scanner] - PATH: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 11:37:36.558 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Started process 340499
2025-09-14 11:37:36.558 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 340499 isRunning=true
2025-09-14 11:37:36.559 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Running /var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 command_loop
2025-09-14 11:38:09.866 [INFO ] [nding.appletv.internal.PyATV$Scanner] - queueing command: app_list
2025-09-14 11:38:09.880 [ERROR] [ding.appletv.internal.AppleTVHandler] - ATV command threw exception: Object of type NameError is not JSON serializable:
Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 516, in _run_command
    stdoute_write_nb(args.output(output_apps(await getattr(atv.apps, command)())))
    ^^^^^^^^^^^^^^^^
NameError: name 'stdoute_write_nb' is not defined

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 618, in appstart
    args.output(await _handle_command(args, abort_sem, storage, loop)),
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 359, in _handle_command
    return await _run_command(atv, args, abort_sem, loop)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 526, in _run_command
    stdout_write_nb(args.output(output(False, values={"exception": e})))
                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/__init__.py", line 231, in dumps
    return _default_encoder.encode(obj)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 200, in encode
    chunks = self.iterencode(o, _one_shot=True)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 258, in iterencode
    return _iterencode(o, 0)
           ^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 180, in default
    raise TypeError(f'Object of type {o.__class__.__name__} '
TypeError: Object of type NameError is not JSON serializable

2025-09-14 11:38:10.188 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Command process=340499 exited.
2025-09-14 11:38:10.189 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 340499 exists.
2025-09-14 11:38:10.193 [WARN ] [nding.appletv.internal.PyATV$Scanner] - PATH: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 11:38:10.194 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Started process 340533
2025-09-14 11:38:10.195 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 340533 isRunning=true
2025-09-14 11:38:10.196 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Running /var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 command_loop
2025-09-14 11:38:33.076 [INFO ] [nding.appletv.internal.PyATV$Scanner] - queueing command: app_list
2025-09-14 11:38:33.097 [ERROR] [ding.appletv.internal.AppleTVHandler] - ATV command threw exception: Object of type NameError is not JSON serializable:
Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 516, in _run_command
    stdoute_write_nb(args.output(output_apps(await getattr(atv.apps, command)())))
    ^^^^^^^^^^^^^^^^
NameError: name 'stdoute_write_nb' is not defined

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 618, in appstart
    args.output(await _handle_command(args, abort_sem, storage, loop)),
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 359, in _handle_command
    return await _run_command(atv, args, abort_sem, loop)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/var/lib/openhab/appletv-binding/lib/python3.11/site-packages/pyatv/scripts/atvscript.py", line 526, in _run_command
    stdout_write_nb(args.output(output(False, values={"exception": e})))
                    ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/__init__.py", line 231, in dumps
    return _default_encoder.encode(obj)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 200, in encode
    chunks = self.iterencode(o, _one_shot=True)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 258, in iterencode
    return _iterencode(o, 0)
           ^^^^^^^^^^^^^^^^^
  File "/usr/lib/python3.11/json/encoder.py", line 180, in default
    raise TypeError(f'Object of type {o.__class__.__name__} '
TypeError: Object of type NameError is not JSON serializable

2025-09-14 11:38:33.408 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Command process=340533 exited.
2025-09-14 11:38:33.409 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 340533 exists.
2025-09-14 11:38:33.413 [WARN ] [nding.appletv.internal.PyATV$Scanner] - PATH: /usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-14 11:38:33.414 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Started process 340563
2025-09-14 11:38:33.414 [INFO ] [nding.appletv.internal.PyATV$Scanner] - Process 340563 isRunning=true
2025-09-14 11:38:33.414 [INFO ] [nding.appletv.internal.PyATV$Scanner] - AppleTVCommandLoop: Running /var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 command_loop
2025-09-14 11:39:57.211 [INFO ] [nding.appletv.internal.PyATV$Scanner] - queueing command: launch_app=com.netflix.Netflix

/var/lib/openhab/tmp/appletv-binding/bin/atvscript -i 1254B978AA8B --raop-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d41393383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462 --airplay-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff51208438b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832 --companion-credentials 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d14dec03843a4cdf61043937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238 launch_app=pl.play.playnow

{“result”: “failure”, “datetime”: “2025-09-14T11:45:47.911594+02:00”, “error”: “unsupported_command”}

hww3 · September 14, 2025, 5:23pm

This errors seem to happen when more than one command is executed on an TvOS 18+ device by a single rsid (That typically means under the same user). The binding runs a command_loop, which is persistent in order to achieve rapid responses to commands and state changes. It also means that no other ATV commands can run as the same user against a given atv device.

A previous version of the binding was causing exactly these problems because it was periodically verifying credentials while the command_loop was running. I’d start by making sure nothing else is trying to interact with your ATV through pyatv and also making sure that you’re using the latest version of the binding. You might need to uninstall the binding and re-install it. You should not need to recreate your ATV things, they’ll just be handler-less during the process and should start back up.

hww3 · September 14, 2025, 5:25pm

Application launching is on the to-do list but isn’t ready yet. Soon, hopefully.

Jacek_Kaczmarczyk · September 14, 2025, 8:44pm

i am pretty sure it’s the last version, you posted the jar yesterday and i grab it from this post.

Thank you i will give you more logs tomorrow

hww3 · September 16, 2025, 6:20pm

I haven’t seen the behavior you’re describing here, though I’m not explicitly using turn_off… I let the ATV turn itself. I have tested it manually and things work as I expect, but maybe there’s something going wrong. Can you send me an example of your code that turns things off and (on, maybe?) You can send it to me directly and I’ll see if I can reproduce the behavior here.

OMR · September 19, 2025, 9:53am

Anyone tried tvos 26 yet?

Jacek_Kaczmarczyk · September 20, 2025, 9:25am

Me juts upgraded the TV OS.

2025-09-20 11:20:20.747 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:20:20.755 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]'
2025-09-20 11:20:20.756 [WARN ] [ding.appletv.internal.AppleTVHandler] - Pairing failed: null
2025-09-20 11:21:20.758 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:21:20.814 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]'
2025-09-20 11:21:20.816 [WARN ] [ding.appletv.internal.AppleTVHandler] - Pairing failed: null
2025-09-20 11:22:20.818 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:22:20.830 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]'
2025-09-20 11:22:20.832 [WARN ] [ding.appletv.internal.AppleTVHandler] - Pairing failed: null
2025-09-20 11:22:43.494 [INFO ] [ding.appletv.internal.AppleTVHandler] - Disposing of AppleTVHandler
2025-09-20 11:22:43.551 [INFO ] [ding.appletv.internal.AppleTVHandler] - Stopping background discovery
2025-09-20 11:22:54.086 [INFO ] [enhab.binding.appletv.internal.PyATV] - Initialize PyATV
2025-09-20 11:22:54.352 [INFO ] [pletv.internal.AppleTVHandlerFactory] - Calling createHandler: appletv:device:12-54-B9-78-AA-8B (ThingTypeUID=appletv:device, Bridge=False, Label=Apple TV sypialnia (AppleTV Gen4K), Status=UNINITIALIZED, StatusInfo=UNINITIALIZED (HANDLER_MISSING_ERROR), SemanticEquipmentTag=null)
2025-09-20 11:22:54.457 [INFO ] [ding.appletv.internal.AppleTVHandler] - AppleTV Properties: {macAddress=12:54:B9:78:AA:8B, modelId=AppleTV Gen4K, vendor=Apple, pairingRaop=Mandatory, pairingAirplay=Mandatory, pairingCompanion=Mandatory, firmwareVersion=TvOS 18.6, deviceId=12:54:B9:78:AA:8B}
2025-09-20 11:22:54.470 [INFO ] [ding.appletv.internal.AppleTVHandler] - Initializing AppleTV
2025-09-20 11:22:54.472 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting background discovery
2025-09-20 11:22:54.493 [INFO ] [ding.appletv.internal.AppleTVHandler] - Pairing Requirement for Airplay: Mandatory
2025-09-20 11:22:54.494 [INFO ] [ding.appletv.internal.AppleTVHandler] - Pairing Requirement for Companion: Mandatory
2025-09-20 11:22:54.498 [INFO ] [ding.appletv.internal.AppleTVHandler] - Pairing Requirement for MRP: Disabled
2025-09-20 11:22:54.499 [INFO ] [ding.appletv.internal.AppleTVHandler] - Pairing Requirement for RAOP: Mandatory
2025-09-20 11:22:54.509 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:22:54.571 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]'
2025-09-20 11:22:54.573 [WARN ] [ding.appletv.internal.AppleTVHandler] - Pairing failed: null
2025-09-20 11:23:14.490 [INFO ] [ding.appletv.internal.AppleTVHandler] - Starting Apple-TV discovery
2025-09-20 11:23:14.492 [INFO ] [enhab.binding.appletv.internal.PyATV] - Scan for AppleTV devices
2025-09-20 11:23:14.493 [INFO ] [enhab.binding.appletv.internal.PyATV] - Sending command scan to deviceId , lid org.openhab.binding.appletv.internal.AppleTVThingConfiguration@2c0bc7ac
2025-09-20 11:23:14.509 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:23:14.571 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]'
2025-09-20 11:23:14.573 [ERROR] [enhab.binding.appletv.internal.PyATV] - Exception on PyATV call: Error executing [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan] (class java.lang.RuntimeException)
java.lang.RuntimeException: Error executing [/var/lib/openhab/tmp/appletv-binding/bin/atvscript, scan]
        at org.openhab.binding.appletv.internal.PyATV.exec(PyATV.java:149) ~[?:?]
        at org.openhab.binding.appletv.internal.PyATV.sendCommands(PyATV.java:134) ~[?:?]
        at org.openhab.binding.appletv.internal.PyATV.scanDevices(PyATV.java:176) ~[?:?]
        at org.openhab.binding.appletv.internal.AppleTVHandlerFactory.scanDevices(AppleTVHandlerFactory.java:142) ~[?:?]
        at org.openhab.binding.appletv.internal.discovery.AppleTVDiscoveryService.startScan(AppleTVDiscoveryService.java:104) ~[?:?]
2025-09-20 11:23:14.606 [ERROR] [enhab.binding.appletv.internal.PyATV] - Scanning for Apple-TV devices failed!
2025-09-20 11:23:14.607 [INFO ] [ding.appletv.internal.AppleTVHandler] - Apple-TV discovery returned null
2025-09-20 11:23:54.578 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:23:54.587 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]'
2025-09-20 11:23:54.588 [WARN ] [ding.appletv.internal.AppleTVHandler] - Pairing failed: null
2025-09-20 11:24:54.590 [WARN ] [enhab.binding.appletv.internal.PyATV] - PaTH: /var/lib/openhab/tmp/appletv-binding/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
2025-09-20 11:24:54.596 [WARN ] [enhab.binding.appletv.internal.PyATV] - Failed to execute commandLine '[/var/lib/openhab/tmp/appletv-binding/bin/atvremote, -i, 1254B978AA8B, --raop-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:8cff57ed265b04c4e75b199cc838dadb7b78082dd4a9007db3de114cfd928ba9:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39663839643535322d313664642d343237392d393833382d633335336633343833303462, --airplay-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:656c0495d7b5aea9ff5120843876f05cc81e87d9b6a69991c9a1be57d3350c17:31313534423937382d414138422d343837412d413932372d433845383644423635314536:30396333396264642d643433332d343238372d396431382d366162386234303935393832, --companion-credentials, 8dcc9a296e6d472c793ddee7e6d8295341266a64447c08c8efa8b10c53aafaf6:a720ba105ea66c46f6905934d18a582bcd2e734dec03843a4cdf61046d5b8b2f:31313534423937382d414138422d343837412d413932372d433845383644423635314536:39643732643133352d623062302d346239322d613434322d343435306437323831386238, mac]'
2025-09-20 11:24:54.597 [WARN ] [ding.appletv.internal.AppleTVHandler] - Pairing failed: null

OMR · September 20, 2025, 9:58am

So no joy with no cigar in sight?

Jacek_Kaczmarczyk · September 24, 2025, 12:12pm

yeah

atvscript scan works for me

(appletv-binding) pi@raspberrypi:~ $ atvscript  scan

{“result”: “success”, “datetime”: “2025-09-24T14:11:00.147798+02:00”, “devices”: [{“name”: “My Music on raspberrypi”, “address”: “192.168.0.136”, “identifier”: “CB4AE6AD56DD6A12”, “all_identifiers”: [“CB4AE6AD56DD6A12”], “device_info”: {“model”: “Unknown”, “operating_system”: “Legacy”, “version”: null, “build_number”: null, “mac”: null}, “model”: “Unknown”, “model_str”: “Unknown”, “services”: [{“protocol”: “dmap”, “port”: 3689, “enabled”: true, “requires_password”: false, “pairing”: “mandatory”}]}, {“name”: “Apple TV sypialnia”, “address”: “192.168.0.52”, “identifier”: “12:54:B9:78:AA:8B”, “all_identifiers”: [“1154B978-AA8B-487A-A927-C8E86DB651E6”, “12:54:B9:78:AA:8B”, “1254B978AA8B”], “device_info”: {“model”: “Gen4K”, “operating_system”: “TvOS”, “version”: “26.0”, “build_number”: null, “mac”: “12:54:B9:78:AA:8B”}, “model”: “Gen4K”, “model_str”: “Apple TV 4K”, “services”: [{“protocol”: “companion”, “port”: 49153, “enabled”: true, “requires_password”: false, “pairing”: “mandatory”}, {“protocol”: “airplay”, “port”: 7000, “enabled”: true, “requires_password”: false, “pairing”: “mandatory”}, {“protocol”: “raop”, “port”: 7000, “enabled”: true, “requires_password”: false, “pairing”: “mandatory”}]}]}(appletv-binding)

Jacek_Kaczmarczyk · September 24, 2025, 12:14pm

ok sorry i know what went wrong, it’s working (i remove the /tmp folder and there was some problem with running the command now i have fix it and it’s working

OMR · October 3, 2025, 2:00pm

tvos 26 woring fine for me too …
But every time OH restarts, my 2 TVs are turned ON.
Need to figure out a solution for that …

hww3 · October 5, 2025, 10:03pm

I just shut all of my devices off and restarted openhab and nothing turned back on. Do you have any rules or scripts that might interact with these devices?

If you send any command to a sleeping device it will wake back up. That is a new behavior designed to make things work the way most people expect them (rather than sending commands to a sleeping device that just end up being ignored).

OMR · October 6, 2025, 2:26pm

Thanks for testing.
No, but I persist everything …
Could it be persistence setting the thing to OFF causing it to turn ON?

hww3 · October 6, 2025, 10:48pm

I guess it’s possible, though I did make things smart enough that the power status channel was excluded from the “turn on first” logic.

But I guess it could be persistence doing something unexpected. Any chance you could temporarily exclude one or both of your devices to see if that fixes the problem?

There might be some clues in the log files if you turn debug on.

Jacek_Kaczmarczyk · October 11, 2025, 10:42am

i have the same problem everytime the openhab restart and the apple tv binding is initalizing my apple tv shutsdown. (previouse state ON)


2025-10-11 12:22:33.537 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'appletv:device:12-54-B9-78-AA-8B' changed from UNINITIALIZED (NOT_YET_READY) to INITIALIZING
2025-10-11 12:22:33.556 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'appletv:device:12-54-B9-78-AA-8B' changed from INITIALIZING to UNKNOWN
2025-10-11 12:22:33.668 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'appletv:device:12-54-B9-78-AA-8B' changed from UNKNOWN to OFFLINE

2025-10-11 12:22:46.918 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'appletv:device:12-54-B9-78-AA-8B' changed from OFFLINE to ONLINE
2025-10-11 12:22:51.467 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Play_Mode' changed from NULL to idle
2025-10-11 12:22:51.469 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Repeat_State' changed from NULL to off
2025-10-11 12:22:51.482 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Shuffle_State' changed from NULL to off
2025-10-11 12:22:51.495 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Media_Type' changed from NULL to unknown
2025-10-11 12:22:51.500 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Album' changed from NULL to
2025-10-11 12:22:51.505 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Artist' changed from NULL to
2025-10-11 12:22:51.515 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Genre' changed from NULL to
2025-10-11 12:22:51.517 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Episode_Number' changed from NULL to
2025-10-11 12:22:51.519 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Series_Name' changed from NULL to
2025-10-11 12:22:51.548 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_App_Identifier' changed from NULL to
2025-10-11 12:22:51.560 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_App_Name' changed from NULL to
2025-10-11 12:22:51.571 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Title' changed from NULL to
2025-10-11 12:22:51.580 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Total_Duration' changed from NULL to 00:00:00
2025-10-11 12:22:51.587 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Position' changed from NULL to 00:00:00
2025-10-11 12:22:51.709 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Artwork' changed from NULL to raw type (image/jpeg): 388 bytes

and then again restarted openhab and my apple tv goes back ON (previous OFF)

2025-10-11 12:30:50.301 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'openweathermap:weather-api:e8537c154e' changed from UNKNOWN to OFFLINE
2025-10-11 12:30:57.797 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Apple_TV_sypialnia_AppleTV_Gen4K_Power_Status' changed from OFF to ON

and again when i restart - nothing happen with the apple TV remained ON and after few additional restart it keeps staying in the same state. Not sure what is happening but i can confirme sometimes it’s changing the state during restart