grep -i cec kodi.log
hdmi_force_cec_address:0=65535
hdmi_force_cec_address:1=65535
hdmi_ignore_cec_init:0=1
2021-01-17 16:12:01.563 T:3011355232 DEBUG: LoadMappings - loaded node "CEC Adapter"
2021-01-17 16:12:01.563 T:3011355232 DEBUG: LoadMappings - loaded node "Pulse-Eight CEC Adapter"
2021-01-17 16:12:01.578 T:2803880816 DEBUG: Thread PeripBusCEC start, auto delete: false
2021-01-17 16:12:02.119 T:2803880816 DEBUG: GetMappingForDevice - device (2708:1001) mapped to CEC Adapter (type = cec)
2021-01-17 16:12:02.127 T:2803880816 DEBUG: InitialiseFeature - using libCEC v4.0.4
2021-01-17 16:12:02.127 T:2609423216 DEBUG: Thread CECAdapter start, auto delete: false
2021-01-17 16:12:02.127 T:2609423216 DEBUG: OpenConnection - opening a connection to the CEC adapter: RPI
2021-01-17 16:12:02.127 T:2803880816 NOTICE: Register - new cec device registered on cec->RPI: CEC Adapter (2708:1001)
2021-01-17 16:12:02.128 T:2617893744 DEBUG: CecLogMessage - Broadcast (F): osd name set to 'Broadcast'
2021-01-17 16:12:02.128 T:2803880816 DEBUG: Thread PeripBusCEC 2803880816 terminating
2021-01-17 16:12:02.128 T:2617893744 DEBUG: CecLogMessage - logical address changed to Free use (e)
2021-01-17 16:12:02.129 T:2617893744 DEBUG: CecLogMessage - Open - vc_cec initialised
2021-01-17 16:12:02.129 T:2617893744 DEBUG: CecLogMessage - connection opened
2021-01-17 16:12:02.129 T:2617893744 DEBUG: CecLogMessage - << Broadcast (F) -> TV (0): POLL
2021-01-17 16:12:02.129 T:2617893744 DEBUG: CecLogMessage - initiator 'Broadcast' is not supported by the CEC adapter. using 'Free use' instead
2021-01-17 16:12:02.130 T:2617893744 DEBUG: CecLogMessage - << e0
2021-01-17 16:12:02.130 T:2617893744 DEBUG: CecLogMessage - processor thread started
2021-01-17 16:12:03.129 T:2617893744 DEBUG: CecLogMessage - command 'feature abort' timeout
2021-01-17 16:12:03.629 T:2617893744 DEBUG: CecLogMessage - initiator 'Broadcast' is not supported by the CEC adapter. using 'Free use' instead
2021-01-17 16:12:03.759 T:2617893744 DEBUG: CecLogMessage - << e0
2021-01-17 16:12:03.759 T:2617893744 DEBUG: CecLogMessage - >> POLL sent
2021-01-17 16:12:03.759 T:2617893744 DEBUG: CecLogMessage - TV (0): device status changed into 'present'
2021-01-17 16:12:03.759 T:2617893744 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
2021-01-17 16:12:03.759 T:2617893744 DEBUG: CecLogMessage - << e0:8c
2021-01-17 16:12:04.659 T:2617893744 DEBUG: CecLogMessage - command 'give device vendor id' timeout
2021-01-17 16:12:05.160 T:2617893744 DEBUG: CecLogMessage - << e0:8c
2021-01-17 16:12:05.191 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=e destination=0 response=0
2021-01-17 16:12:06.160 T:2617893744 DEBUG: CecLogMessage - command 'give device vendor id' timeout
2021-01-17 16:12:06.661 T:2617893744 DEBUG: CecLogMessage - registering new CEC client - v4.0.0
2021-01-17 16:12:06.661 T:2617893744 DEBUG: CecLogMessage - detecting logical address for type 'recording device'
2021-01-17 16:12:06.661 T:2617893744 DEBUG: CecLogMessage - trying logical address 'Recorder 1'
2021-01-17 16:12:06.661 T:2617893744 DEBUG: CecLogMessage - << Recorder 1 (1) -> Recorder 1 (1): POLL
2021-01-17 16:12:06.661 T:2617893744 DEBUG: CecLogMessage - << 11
2021-01-17 16:12:11.450 T:2617893744 DEBUG: CecLogMessage - << 11
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - >> POLL not sent
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - using logical address 'Recorder 1'
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): device status changed into 'handled by libCEC'
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): power status changed from 'unknown' to 'on'
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): vendor = Pulse Eight (001582)
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): CEC version 1.4
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - AllocateLogicalAddresses - device '0', type 'recording device', LA '1'
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - logical address changed to Recorder 1 (1)
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): osd name set to 'Kodi'
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): menu language set to 'eng'
2021-01-17 16:12:15.911 T:2617893744 DEBUG: CecLogMessage - GetPhysicalAddress - physical address = 1000
2021-01-17 16:12:15.912 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1): physical address changed from ffff to 1000
2021-01-17 16:12:15.912 T:2617893744 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2021-01-17 16:12:15.912 T:2617893744 DEBUG: CecLogMessage - << 1f:84:10:00:01
2021-01-17 16:12:16.912 T:2617893744 DEBUG: CecLogMessage - command 'report physical address' timeout
2021-01-17 16:12:17.412 T:2617893744 DEBUG: CecLogMessage - << 1f:84:10:00:01
2021-01-17 16:12:17.442 T:2617893744 DEBUG: CecLogMessage - making Recorder 1 (1) the active source
2021-01-17 16:12:17.442 T:2617893744 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
2021-01-17 16:12:17.442 T:2617893744 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
2021-01-17 16:12:17.442 T:2617893744 DEBUG: CecLogMessage - scheduling active source message for 'Recorder 1'
2021-01-17 16:12:17.446 T:2617893744 DEBUG: CecLogMessage - CEC client registered: libCEC version = 4.0.4, client version = 4.0.0, firmware version = 1, logical address(es) = Recorder 1 (1
) , physical address: 1.0.0.0, compiled on Sat Oct 24 20:14:00 UTC 2020 by jenkins@prometheus on Linux 4.15.0-118-generic (x86_64), features: P8_USB, DRM, P8_detect, RPi
2021-01-17 16:12:17.446 T:2617893744 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
2021-01-17 16:12:17.446 T:2617893744 DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
2021-01-17 16:12:18.443 T:2617893744 DEBUG: CecLogMessage - command 'set osd name' timeout
2021-01-17 16:12:18.943 T:2617893744 DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
2021-01-17 16:12:18.972 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=84 initiator=1 destination=f response=0
2021-01-17 16:12:19.943 T:2617893744 DEBUG: CecLogMessage - command 'set osd name' timeout
2021-01-17 16:12:20.443 T:2617893744 DEBUG: CecLogMessage - << requesting power status of 'TV' (0)
2021-01-17 16:12:20.443 T:2617893744 DEBUG: CecLogMessage - << 10:8f
2021-01-17 16:12:20.504 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=47 initiator=1 destination=0 response=0
2021-01-17 16:12:21.443 T:2617893744 DEBUG: CecLogMessage - command 'give device power status' timeout
2021-01-17 16:12:21.944 T:2617893744 DEBUG: CecLogMessage - << 10:8f
2021-01-17 16:12:22.035 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=47 initiator=1 destination=0 response=0
2021-01-17 16:12:22.944 T:2617893744 DEBUG: CecLogMessage - command 'give device power status' timeout
2021-01-17 16:12:23.444 T:2609423216 DEBUG: OpenConnection - connection to the CEC adapter opened
2021-01-17 16:12:23.444 T:2617893744 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'unknown'
2021-01-17 16:12:23.444 T:2609423216 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC
2021-01-17 16:12:23.444 T:2617893744 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
2021-01-17 16:12:23.444 T:2617893744 DEBUG: CecLogMessage - << 10:8c
2021-01-17 16:12:23.444 T:2490348400 DEBUG: Thread CECAdapterUpdate start, auto delete: false
2021-01-17 16:12:23.565 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=8f initiator=1 destination=0 response=0
2021-01-17 16:12:24.445 T:2617893744 DEBUG: CecLogMessage - command 'give device vendor id' timeout
2021-01-17 16:12:24.945 T:2617893744 DEBUG: CecLogMessage - << 10:8c
2021-01-17 16:12:25.098 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=8f initiator=1 destination=0 response=0
2021-01-17 16:12:25.945 T:2617893744 DEBUG: CecLogMessage - command 'give device vendor id' timeout
2021-01-17 16:12:26.445 T:2617893744 DEBUG: CecLogMessage - << powering on 'TV' (0)
2021-01-17 16:12:26.445 T:2617893744 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
2021-01-17 16:12:26.445 T:2617893744 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on'
2021-01-17 16:12:26.445 T:2617893744 DEBUG: CecLogMessage - << 10:04
2021-01-17 16:12:26.632 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0
2021-01-17 16:12:27.445 T:2617893744 DEBUG: CecLogMessage - command 'image view on' timeout
2021-01-17 16:12:27.945 T:2617893744 DEBUG: CecLogMessage - << 10:04
2021-01-17 16:12:28.163 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0
2021-01-17 16:12:28.946 T:2617893744 DEBUG: CecLogMessage - command 'image view on' timeout
2021-01-17 16:12:29.446 T:2617893744 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000)
2021-01-17 16:12:29.446 T:2617893744 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
2021-01-17 16:12:29.446 T:2617893744 DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
2021-01-17 16:12:29.446 T:2617893744 DEBUG: CecLogMessage - << 1f:82:10:00
2021-01-17 16:12:29.446 T:2617893744 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0)
2021-01-17 16:12:29.695 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0
2021-01-17 16:12:30.446 T:2617893744 DEBUG: CecLogMessage - command 'active source' timeout
2021-01-17 16:12:30.946 T:2617893744 DEBUG: CecLogMessage - << 1f:82:10:00
2021-01-17 16:12:31.231 T:2617893744 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0
2021-01-17 16:12:31.947 T:2617893744 DEBUG: CecLogMessage - command 'active source' timeout