# turn tv off, avr auto off, not turning back on by itself: 2024-04-08 14:39:49.612 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:39:49.612 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:01:10 2024-04-08 14:39:49.612 T:830 debug : CecLogMessage - TV (0): power status changed from 'on' to 'standby' 2024-04-08 14:39:49.612 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:39:49.714 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:39:49.714 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:39:49.714 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 2024-04-08 14:39:50.308 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:39:50.308 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:39:50.388 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:39:50.818 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:39:51.058 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:39:51.445 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:39:51.445 T:830 debug : CecLogMessage - >> 0f:86:28:00 2024-04-08 14:39:51.445 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2800 2024-04-08 14:39:51.445 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:39:51.882 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:39:51.882 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:39:51.882 T:830 debug : CecLogMessage - TV (0): power status changed from 'standby' to 'on' 2024-04-08 14:39:51.882 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:39:51.882 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:39:51.882 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:39:51.993 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:39:53.067 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:39:53.067 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:39:53.067 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:39:53.067 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:39:53.067 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:39:53.178 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:39:54.396 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:39:54.396 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:39:54.397 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:39:54.397 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:39:54.397 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:39:54.507 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 # turning tv back on, avr should power on but doesn't, display blank 2024-04-08 14:40:38.427 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:40:38.428 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:00:00 2024-04-08 14:40:38.428 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:40:38.428 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:40:38.779 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:40:38.779 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:38.779 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:38.894 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:39.458 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=0f opcode=80 2024-04-08 14:40:39.458 T:830 debug : CecLogMessage - >> 0f:80:00:00:20:00 2024-04-08 14:40:39.458 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80) 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=81 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - >> 5f:81:25:00 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:40:39.572 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81) 2024-04-08 14:40:39.635 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:40:39.635 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:39.635 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:39.750 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:39.750 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:40:39.750 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:40:39.841 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:40.476 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:40:40.587 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:42.134 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:40:42.134 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:40:42.134 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:40:42.377 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:40:42.377 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:40:42.377 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:40:43.106 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=83 2024-04-08 14:40:43.106 T:830 debug : CecLogMessage - >> 04:83 2024-04-08 14:40:43.106 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:40:43.106 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:40:43.106 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give physical address (83) 2024-04-08 14:40:43.241 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:40:43.338 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:40:43.338 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:40:43.338 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:40:43.338 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:40:43.339 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:40:43.473 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:40:43.650 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:40:43.650 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:40:43.650 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:40:43.650 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:40:44.001 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:40:44.001 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:40:44.001 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:40:44.001 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:40:44.224 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:40:44.224 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:40:44.224 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:44.224 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:44.224 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:40:44.335 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:45.335 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:40:45.335 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:40:45.388 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:40:45.388 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:45.388 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:45.574 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:40:45.685 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:45.685 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:40:45.685 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:40:45.776 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:40:45.776 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:40:45.776 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:45.776 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:45.776 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:40:45.891 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:47.097 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:40:47.097 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:40:47.097 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:40:47.097 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:40:47.097 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:40:47.208 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:40:48.444 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=84 2024-04-08 14:40:48.444 T:830 debug : CecLogMessage - >> 5f:84:20:00:05 2024-04-08 14:40:48.444 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84) 2024-04-08 14:40:48.685 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:40:48.685 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:40:48.685 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # turning tv back off (while avr was off and no picture) 2024-04-08 14:40:58.482 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:40:58.482 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:01:10 2024-04-08 14:40:58.482 T:830 debug : CecLogMessage - TV (0): power status changed from 'on' to 'standby' 2024-04-08 14:40:58.482 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:40:58.584 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:40:58.584 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:40:58.584 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 2024-04-08 14:40:59.178 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:40:59.178 T:830 debug : CecLogMessage - >> 0f:36 # turning tv back on, avr doesnt auto power on, manually turning avr on selexting kodi input 2024-04-08 14:42:57.637 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:42:57.637 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:00:00 2024-04-08 14:42:57.637 T:830 debug : CecLogMessage - TV (0): power status changed from 'standby' to 'on' 2024-04-08 14:42:57.637 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:42:57.637 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:42:57.988 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:42:57.988 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:42:57.988 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:42:58.103 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:42:58.677 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=0f opcode=80 2024-04-08 14:42:58.678 T:830 debug : CecLogMessage - >> 0f:80:00:00:20:00 2024-04-08 14:42:58.678 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80) 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=81 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - >> 5f:81:25:00 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:42:58.792 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81) 2024-04-08 14:42:58.855 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:42:58.855 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:42:58.855 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:42:58.970 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:42:58.970 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:42:58.970 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:42:59.061 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:42:59.706 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:42:59.797 T:796 debug : ------ Window Deinit () ------ 2024-04-08 14:42:59.817 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:01.373 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:43:01.373 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:43:01.373 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:43:01.615 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:43:01.615 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:43:01.615 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:43:02.345 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=83 2024-04-08 14:43:02.345 T:830 debug : CecLogMessage - >> 04:83 2024-04-08 14:43:02.345 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:43:02.345 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:43:02.345 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give physical address (83) 2024-04-08 14:43:02.480 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:43:02.575 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:43:02.575 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:43:02.575 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:43:02.575 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:43:02.575 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:43:02.710 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:43:02.879 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:43:02.879 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:43:02.879 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:43:02.879 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:43:03.230 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:43:03.230 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:43:03.230 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:43:03.230 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:43:03.453 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:03.453 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:03.453 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:03.453 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:03.453 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:03.564 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:04.564 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:43:04.564 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:43:04.617 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:43:04.617 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:04.617 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:04.803 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:04.914 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:04.914 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:43:04.914 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:43:05.005 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:43:05.005 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:05.005 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:05.005 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:05.005 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:05.120 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:05.490 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:43:07.502 T:830 debug : CecLogMessage - changing physical address to FFFF 2024-04-08 14:43:07.503 T:830 debug : CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff 2024-04-08 14:43:07.503 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:43:07.876 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:43:08.197 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:43:08.198 T:830 debug : CecLogMessage - changing physical address to 2500 2024-04-08 14:43:08.198 T:830 debug : CecLogMessage - marking Playback 1 (4) as inactive source 2024-04-08 14:43:08.198 T:830 debug : CecLogMessage - >> source deactivated: Playback 1 (4) 2024-04-08 14:43:08.198 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:43:08.198 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:43:08.714 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:43:08.714 T:830 debug : CecLogMessage - making Playback 1 (4) the active source 2024-04-08 14:43:08.714 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:43:08.714 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:43:08.714 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:43:08.714 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:43:08.914 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:43:08.914 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:08.914 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:09.029 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:09.029 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:43:09.029 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:43:09.120 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:43:09.120 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:43:09.125 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:43:09.125 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:43:09.125 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 2024-04-08 14:43:09.259 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:43:09.259 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:43:09.259 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:43:09.418 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:43:09.418 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:43:09.418 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:43:09.644 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=a7 2024-04-08 14:43:09.644 T:830 debug : CecLogMessage - >> 5f:a7:00:00 2024-04-08 14:43:09.644 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): UNKNOWN (A7) 2024-04-08 14:43:09.831 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:09.831 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:09.831 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:09.831 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:09.831 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:09.942 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:10.008 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=5f opcode=85 2024-04-08 14:43:10.008 T:830 debug : CecLogMessage - >> 5f:85 2024-04-08 14:43:10.008 T:830 debug : CecLogMessage - >> 5 requests active source 2024-04-08 14:43:10.008 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:10.008 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:10.008 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): request active source (85) 2024-04-08 14:43:10.119 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:10.414 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:43:10.414 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:43:10.414 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 2024-04-08 14:43:10.764 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:43:11.401 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:43:11.535 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:43:11.544 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:43:12.067 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:43:12.242 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:43:12.605 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:12.605 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:12.605 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:12.605 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:12.605 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:12.855 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:13.199 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:43:13.199 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:43:13.199 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:43:13.199 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:43:13.199 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:43:13.334 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:43:13.618 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:13.618 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:13.618 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:13.618 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:13.618 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:13.729 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:14.833 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:43:14.833 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:43:14.833 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) # turning tv off, avr aut off, then tv + avr turning back on by itself and kodi showing on tv 2024-04-08 14:43:55.669 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:43:56.300 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:43:56.381 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:43:57.419 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:43:57.419 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:43:57.622 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:43:58.181 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:58.181 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:58.181 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:58.181 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:58.181 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:58.292 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:59.239 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:43:59.239 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:43:59.239 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:43:59.239 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:43:59.239 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:43:59.350 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:43:59.519 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:43:59.520 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:43:59.520 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:43:59.520 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:43:59.520 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:43:59.654 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:44:00.196 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:44:00.196 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:44:00.196 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:00.196 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:00.196 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:44:00.307 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=5f opcode=80 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - >> 5f:80:28:00:25:00 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:44:00.893 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing change (80) 2024-04-08 14:44:00.956 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:44:00.956 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:00.956 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:01.040 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:44:01.071 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:01.071 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:44:01.071 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:44:01.071 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:44:01.071 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:44:01.071 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:44:01.694 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:44:01.767 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:44:02.770 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:44:02.770 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:44:02.932 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:44:03.102 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:44:05.113 T:830 debug : CecLogMessage - changing physical address to FFFF 2024-04-08 14:44:05.113 T:830 debug : CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff 2024-04-08 14:44:05.113 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:44:05.512 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:44:05.667 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:44:05.667 T:830 debug : CecLogMessage - changing physical address to 2500 2024-04-08 14:44:05.667 T:830 debug : CecLogMessage - marking Playback 1 (4) as inactive source 2024-04-08 14:44:05.667 T:830 debug : CecLogMessage - >> source deactivated: Playback 1 (4) 2024-04-08 14:44:05.667 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:44:05.667 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:44:06.183 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:44:06.183 T:830 debug : CecLogMessage - making Playback 1 (4) the active source 2024-04-08 14:44:06.183 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:44:06.183 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:44:06.183 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:44:06.183 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:44:06.432 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:44:06.432 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:06.432 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:06.547 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:06.547 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:44:06.547 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:44:06.638 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:44:06.638 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:44:06.644 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:44:06.644 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:44:06.644 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:06.644 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:06.644 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:44:06.753 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:06.891 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=84 2024-04-08 14:44:06.891 T:830 debug : CecLogMessage - >> 5f:84:20:00:05 2024-04-08 14:44:06.891 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84) 2024-04-08 14:44:07.050 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:44:07.050 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:44:07.050 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 2024-04-08 14:44:07.276 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:44:07.276 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:44:07.276 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:44:07.598 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:44:07.598 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:44:07.598 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:44:07.785 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:44:07.785 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:44:07.785 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:07.785 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:07.785 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:44:07.896 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:08.030 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=a7 2024-04-08 14:44:08.030 T:830 debug : CecLogMessage - >> 5f:a7:00:00 2024-04-08 14:44:08.030 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): UNKNOWN (A7) 2024-04-08 14:44:08.116 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=5f opcode=85 2024-04-08 14:44:08.116 T:830 debug : CecLogMessage - >> 5f:85 2024-04-08 14:44:08.116 T:830 debug : CecLogMessage - >> 5 requests active source 2024-04-08 14:44:08.116 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:08.116 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:08.116 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): request active source (85) 2024-04-08 14:44:08.227 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:08.620 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:44:09.267 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:44:09.372 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:44:10.697 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:44:10.697 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:44:10.970 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:44:12.187 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:44:12.187 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:44:12.187 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:44:12.187 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:44:12.187 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:44:12.322 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:44:12.492 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:44:12.492 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:44:12.492 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:44:12.493 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:44:12.493 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:44:12.627 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:44:12.869 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:44:12.869 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:44:12.869 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:44:12.869 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:44:13.220 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:44:13.220 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:44:13.220 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:44:13.221 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:44:13.566 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:44:13.566 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:44:13.566 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:13.566 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:13.566 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:44:13.677 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:14.677 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:44:14.677 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:44:14.730 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:44:14.730 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:14.730 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:14.916 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:44:15.027 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:15.027 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:44:15.027 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:44:15.118 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:44:15.118 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:44:15.118 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:15.118 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:15.118 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:44:15.233 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:16.451 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:44:16.451 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:44:16.451 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:44:16.451 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:44:16.451 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:44:16.562 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:44:17.784 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:44:17.784 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:44:17.784 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # turning tv off, avr auto off, stays off 2024-04-08 14:45:31.147 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:45:31.147 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:01:10 2024-04-08 14:45:31.147 T:830 debug : CecLogMessage - TV (0): power status changed from 'on' to 'standby' 2024-04-08 14:45:31.147 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:45:31.244 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:45:31.244 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:45:31.244 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 2024-04-08 14:45:31.837 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:45:31.837 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:45:31.909 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:45:32.338 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:45:32.585 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:45:32.972 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:45:32.972 T:830 debug : CecLogMessage - >> 0f:86:28:00 2024-04-08 14:45:32.972 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2800 2024-04-08 14:45:32.972 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:45:33.409 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:45:33.409 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:45:33.409 T:830 debug : CecLogMessage - TV (0): power status changed from 'standby' to 'on' 2024-04-08 14:45:33.409 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:45:33.409 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:45:33.409 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:45:33.520 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:45:34.588 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:45:34.588 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:45:34.588 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:45:34.588 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:45:34.588 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:45:34.699 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:45:35.921 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:45:35.921 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:45:35.921 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:45:35.921 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:45:35.921 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:45:36.032 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 # turning tv back on, avr doesnt auto on, displya blank 2024-04-08 14:45:59.003 T:796 debug : ------ Window Init () ------ 2024-04-08 14:46:01.495 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:46:01.495 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:00:00 2024-04-08 14:46:01.495 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:46:01.495 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:46:01.846 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:46:01.847 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:01.847 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:01.962 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:02.527 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=0f opcode=80 2024-04-08 14:46:02.527 T:830 debug : CecLogMessage - >> 0f:80:00:00:20:00 2024-04-08 14:46:02.527 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80) 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=81 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - >> 5f:81:25:00 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:46:02.642 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81) 2024-04-08 14:46:02.705 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:46:02.705 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:02.705 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:02.820 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:02.820 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:46:02.820 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:46:02.911 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:46:03.544 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:46:03.544 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:46:03.545 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:46:03.545 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:46:03.545 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:46:03.545 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:03.545 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:03.545 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:46:03.651 T:796 debug : ------ Window Deinit () ------ 2024-04-08 14:46:03.655 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:05.203 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:46:05.203 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:46:05.203 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:46:05.438 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:46:05.438 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:46:05.438 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:46:06.165 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=83 2024-04-08 14:46:06.165 T:830 debug : CecLogMessage - >> 04:83 2024-04-08 14:46:06.165 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:46:06.165 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:46:06.165 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give physical address (83) 2024-04-08 14:46:06.300 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:46:06.398 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:46:06.398 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:46:06.398 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:46:06.398 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:46:06.398 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:46:06.533 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:46:06.709 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:46:06.709 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:46:06.709 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:46:06.709 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:46:07.060 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:46:07.060 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:46:07.060 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:46:07.060 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:46:07.283 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:46:07.283 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:46:07.283 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:07.283 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:07.283 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:46:07.394 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:08.394 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:46:08.394 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:46:08.447 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:46:08.447 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:08.447 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:08.633 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:46:08.744 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:08.744 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:46:08.744 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:46:08.835 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:46:08.835 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:46:08.835 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:08.835 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:08.835 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:46:08.950 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:10.156 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:46:10.156 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:46:10.157 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:46:10.157 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:46:10.157 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:46:10.267 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:46:11.503 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=84 2024-04-08 14:46:11.503 T:830 debug : CecLogMessage - >> 5f:84:20:00:05 2024-04-08 14:46:11.503 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84) 2024-04-08 14:46:11.745 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:46:11.745 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:46:11.745 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 2024-04-08 14:46:21.131 T:796 debug : ------ Window Init (DialogExtendedProgressBar.xml) ------ 2024-04-08 14:46:33.800 T:796 debug : ------ Window Deinit (DialogExtendedProgressBar.xml) ------ # turn tv off nothing else happens besides tv off 2024-04-08 14:47:52.458 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:47:52.458 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:01:10 2024-04-08 14:47:52.458 T:830 debug : CecLogMessage - TV (0): power status changed from 'on' to 'standby' 2024-04-08 14:47:52.458 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:47:52.553 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:47:52.553 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:47:52.553 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 2024-04-08 14:47:53.148 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:47:53.148 T:830 debug : CecLogMessage - >> 0f:36 # turn tv on, nothing else happens besides tv on 2024-04-08 14:48:24.867 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:48:24.867 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:00:00 2024-04-08 14:48:24.867 T:830 debug : CecLogMessage - TV (0): power status changed from 'standby' to 'on' 2024-04-08 14:48:24.867 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:48:24.867 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:48:25.218 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:48:25.218 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:25.218 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:25.333 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:25.897 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=0f opcode=80 2024-04-08 14:48:25.897 T:830 debug : CecLogMessage - >> 0f:80:00:00:20:00 2024-04-08 14:48:25.897 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80) 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=81 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - >> 5f:81:25:00 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:48:26.011 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81) 2024-04-08 14:48:26.074 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:48:26.074 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:26.074 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:26.189 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:26.189 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:48:26.189 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:48:26.280 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:48:26.913 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:26.914 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:48:27.024 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:28.573 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:48:28.573 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:48:28.573 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:48:28.817 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:48:28.817 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:48:28.817 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:48:29.545 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=83 2024-04-08 14:48:29.545 T:830 debug : CecLogMessage - >> 04:83 2024-04-08 14:48:29.546 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:48:29.546 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:48:29.546 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give physical address (83) 2024-04-08 14:48:29.680 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:48:29.778 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:48:29.778 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:48:29.778 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:48:29.778 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:48:29.778 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:48:29.913 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:48:30.090 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:48:30.090 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:48:30.090 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:48:30.090 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:48:30.441 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:48:30.441 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:48:30.441 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:48:30.441 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:48:30.663 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:48:30.663 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:48:30.663 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:30.663 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:30.663 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:48:30.774 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:31.774 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:48:31.774 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:48:31.827 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:48:31.827 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:31.827 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:32.013 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:48:32.124 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:32.124 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:48:32.124 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:48:32.215 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:48:32.215 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:48:32.215 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:32.215 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:32.215 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:48:32.330 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:33.536 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:48:33.536 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:48:33.536 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:48:33.536 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:48:33.536 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:48:33.647 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:48:34.883 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=84 2024-04-08 14:48:34.883 T:830 debug : CecLogMessage - >> 5f:84:20:00:05 2024-04-08 14:48:34.883 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84) 2024-04-08 14:48:35.125 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:48:35.125 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:48:35.125 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # turn tv off again, nothing happens besides tv off 2024-04-08 14:49:22.031 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:49:22.031 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:01:10 2024-04-08 14:49:22.031 T:830 debug : CecLogMessage - TV (0): power status changed from 'on' to 'standby' 2024-04-08 14:49:22.031 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:49:22.133 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:49:22.133 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:49:22.134 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 2024-04-08 14:49:22.727 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:49:22.728 T:830 debug : CecLogMessage - >> 0f:36 # turn tv back on, nothing except tv on 2024-04-08 14:49:50.901 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:49:50.902 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:00:00 2024-04-08 14:49:50.902 T:830 debug : CecLogMessage - TV (0): power status changed from 'standby' to 'on' 2024-04-08 14:49:50.902 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:49:50.902 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:49:51.250 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:49:51.250 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:51.250 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:51.365 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:49:51.927 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=0f opcode=80 2024-04-08 14:49:51.927 T:830 debug : CecLogMessage - >> 0f:80:00:00:20:00 2024-04-08 14:49:51.927 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80) 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=81 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - >> 5f:81:25:00 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:49:52.042 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81) 2024-04-08 14:49:52.105 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:49:52.105 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:52.105 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:52.220 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:49:52.220 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:49:52.220 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:49:52.311 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:52.945 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:49:53.056 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:49:53.067 T:796 debug : ------ Window Deinit () ------ 2024-04-08 14:49:54.603 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:49:54.603 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:49:54.603 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:49:54.846 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:49:54.846 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:49:54.846 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:49:55.575 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=83 2024-04-08 14:49:55.575 T:830 debug : CecLogMessage - >> 04:83 2024-04-08 14:49:55.575 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:49:55.575 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:49:55.575 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give physical address (83) 2024-04-08 14:49:55.710 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:49:55.808 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:49:55.808 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:49:55.808 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:49:55.808 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:49:55.808 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:49:55.943 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:49:56.120 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:49:56.120 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:49:56.120 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:49:56.120 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:49:56.471 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:49:56.471 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:49:56.471 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:49:56.471 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:49:56.693 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:49:56.693 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:49:56.693 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:56.693 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:56.693 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:49:56.804 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:49:57.804 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:49:57.805 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:49:57.857 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:49:57.857 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:57.857 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:58.043 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:49:58.154 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:49:58.154 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:49:58.154 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:49:58.245 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:49:58.245 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:49:58.245 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:58.245 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:58.245 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:49:58.360 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:49:59.566 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:49:59.567 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:49:59.567 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:49:59.567 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:49:59.567 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:49:59.677 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:50:00.913 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=84 2024-04-08 14:50:00.913 T:830 debug : CecLogMessage - >> 5f:84:20:00:05 2024-04-08 14:50:00.913 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84) 2024-04-08 14:50:01.155 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:50:01.155 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:50:01.155 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # while tv on, manually select kodi input on avr (avr turns on, shows black screen) 2024-04-08 14:50:27.423 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:50:29.349 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:50:29.422 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:50:30.396 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:50:30.396 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:50:30.602 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:50:31.013 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:50:31.013 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:50:31.013 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:50:31.148 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=a7 2024-04-08 14:50:31.148 T:830 debug : CecLogMessage - >> 5f:a7:00:00 2024-04-08 14:50:31.148 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): UNKNOWN (A7) 2024-04-08 14:50:31.234 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=5f opcode=85 2024-04-08 14:50:31.234 T:830 debug : CecLogMessage - >> 5f:85 2024-04-08 14:50:31.234 T:830 debug : CecLogMessage - >> 5 requests active source 2024-04-08 14:50:31.234 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:50:31.234 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:50:31.234 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): request active source (85) 2024-04-08 14:50:31.345 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:50:31.577 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:50:31.578 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:50:31.578 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:50:31.578 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:50:31.578 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:50:31.712 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:50:33.100 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:50:33.100 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:50:33.100 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:50:34.085 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:50:34.085 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:50:34.085 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:50:34.085 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:50:34.085 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:50:34.220 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:50:34.389 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:50:34.389 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:50:34.389 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:50:34.389 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:50:34.740 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:50:34.740 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:50:34.740 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:50:34.740 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:50:34.964 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:50:34.965 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:50:34.965 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:50:34.965 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:50:34.965 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:50:35.075 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:50:36.076 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:50:36.076 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:50:36.128 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:50:36.128 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:50:36.128 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:50:36.314 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:50:36.425 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:50:36.426 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:50:36.426 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:50:36.516 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:50:36.517 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:50:36.517 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:50:36.517 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:50:36.517 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:50:36.632 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:50:37.838 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:50:37.838 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:50:37.838 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:50:37.838 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:50:37.838 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:50:37.949 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 # pressing up/down key on tv remote while in state above 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=04 opcode=44 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - >> 04:44:01 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - key pressed: up (1) current(ff) duration(0) 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - Changed key up (1) D:0ms cur:ff 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - key pressed: up (1, 0) 2024-04-08 14:51:18.823 T:830 debug : PushCecKeypress - received key a6 duration 0 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - CheckKeypressTimeout T:1222.432 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - Key up: idle (duration:0) (1) timeout:-631956640ms (rel:500,rep:0,prs:500,rel:0) 2024-04-08 14:51:18.823 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 2024-04-08 14:51:18.834 T:796 debug : HandleKey: 166 (0xa6, obc89) pressed, window 10016, action is Up 2024-04-08 14:51:18.923 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=45 2024-04-08 14:51:18.923 T:830 debug : CecLogMessage - >> 04:45 2024-04-08 14:51:18.923 T:830 debug : CecLogMessage - key released: up (1) D:100ms 2024-04-08 14:51:18.923 T:830 debug : PushCecKeypress - received key a6 duration 100 2024-04-08 14:51:18.923 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=04 opcode=44 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - >> 04:44:02 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - key pressed: down (2) current(ff) duration(0) 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - Changed key down (2) D:0ms cur:ff 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - key pressed: down (2, 0) 2024-04-08 14:51:20.871 T:830 debug : PushCecKeypress - received key a7 duration 0 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - CheckKeypressTimeout T:1224.480 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - Key down: idle (duration:0) (2) timeout:-631956640ms (rel:500,rep:0,prs:500,rel:0) 2024-04-08 14:51:20.871 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 2024-04-08 14:51:20.875 T:796 debug : HandleKey: 167 (0xa7, obc88) pressed, window 10016, action is Down 2024-04-08 14:51:20.974 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=45 2024-04-08 14:51:20.974 T:830 debug : CecLogMessage - >> 04:45 2024-04-08 14:51:20.974 T:830 debug : CecLogMessage - key released: down (2) D:103ms 2024-04-08 14:51:20.974 T:830 debug : PushCecKeypress - received key a7 duration 103 2024-04-08 14:51:20.974 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) # switch to over input on avr (nothing connected to it) 2024-04-08 14:51:45.593 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=5f opcode=80 2024-04-08 14:51:45.593 T:830 debug : CecLogMessage - >> 5f:80:25:00:24:00 2024-04-08 14:51:45.593 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing change (80) 2024-04-08 14:51:46.214 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:51:46.214 T:830 debug : CecLogMessage - >> 0f:86:24:00 2024-04-08 14:51:46.214 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2400 2024-04-08 14:51:46.214 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) # switch back to kodi input on avr, now shows kodi screen 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=5f opcode=80 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - >> 5f:80:24:00:25:00 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:52:12.396 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing change (80) 2024-04-08 14:52:12.459 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:52:12.459 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:12.459 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:12.574 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:52:12.574 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:52:12.574 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:52:12.665 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:12.782 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:52:12.893 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 # switch off tv from state above, avr switches off too 2024-04-08 14:52:48.612 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:52:50.364 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:52:50.561 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:52:51.121 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:52:51.121 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:52:51.121 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:51.121 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:51.121 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:52:51.232 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:52:52.176 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:52:52.177 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:52:52.181 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:52.181 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:52.181 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:52:52.287 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:52:52.460 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:52:52.460 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:52:52.460 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:52:52.460 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:52:52.460 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:52:52.595 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:52:53.126 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:52:53.126 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:52:53.126 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:53.126 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:53.126 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:52:53.237 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=5f opcode=80 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - >> 5f:80:28:00:25:00 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:52:53.842 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing change (80) 2024-04-08 14:52:53.905 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:52:53.905 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:53.905 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:53.975 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:52:54.020 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:52:54.020 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:52:54.020 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:52:54.020 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:52:54.020 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:52:54.020 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:52:54.620 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:52:54.711 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:52:55.691 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:52:55.691 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 # and then turns itself + tvback on, kodi input selected automatically, no picture 2024-04-08 14:52:56.018 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:52:58.036 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:52:58.036 T:830 debug : CecLogMessage - changing physical address to FFFF 2024-04-08 14:52:58.036 T:830 debug : CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff 2024-04-08 14:52:58.037 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:52:58.412 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:52:58.581 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:52:58.581 T:830 debug : CecLogMessage - changing physical address to 2500 2024-04-08 14:52:58.581 T:830 debug : CecLogMessage - marking Playback 1 (4) as inactive source 2024-04-08 14:52:58.581 T:830 debug : CecLogMessage - >> source deactivated: Playback 1 (4) 2024-04-08 14:52:58.581 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:52:58.581 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:52:59.032 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:52:59.032 T:830 debug : CecLogMessage - making Playback 1 (4) the active source 2024-04-08 14:52:59.032 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:52:59.032 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:52:59.032 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:52:59.032 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:52:59.098 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:52:59.099 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:59.099 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:59.214 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:52:59.214 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:52:59.214 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:52:59.342 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:52:59.343 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:52:59.814 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:52:59.814 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:52:59.814 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:52:59.814 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:52:59.814 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:52:59.925 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:53:00.361 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:53:00.846 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:53:00.951 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:53:01.411 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:53:01.411 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:53:01.658 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:53:01.997 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=5f opcode=85 2024-04-08 14:53:01.997 T:830 debug : CecLogMessage - >> 5f:85 2024-04-08 14:53:01.997 T:830 debug : CecLogMessage - >> 5 requests active source 2024-04-08 14:53:01.997 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:53:01.997 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:53:01.997 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): request active source (85) 2024-04-08 14:53:02.108 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:53:02.294 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:53:02.294 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:53:02.294 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:53:02.294 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:53:02.294 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:53:02.405 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:53:02.638 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:53:02.638 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:53:02.638 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:53:02.638 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:53:02.638 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:53:02.773 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:53:03.186 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:53:03.186 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:53:03.190 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:53:03.190 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:53:03.190 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:53:03.297 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:53:04.211 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:53:04.211 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:53:04.211 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:53:04.449 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:53:04.449 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:53:04.449 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:53:04.449 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:53:04.449 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:53:04.584 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:53:05.627 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:53:05.627 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:53:05.627 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # manually switch tv off, nothing turns back on 2024-04-08 14:54:01.680 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:54:01.680 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:01:10 2024-04-08 14:54:01.681 T:830 debug : CecLogMessage - TV (0): power status changed from 'on' to 'standby' 2024-04-08 14:54:01.681 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:54:01.774 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:54:01.774 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:54:01.774 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 2024-04-08 14:54:02.368 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36 2024-04-08 14:54:02.368 T:830 debug : CecLogMessage - >> 0f:36 2024-04-08 14:54:02.431 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:54:02.863 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:54:03.103 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:54:03.490 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:54:03.490 T:830 debug : CecLogMessage - >> 0f:86:28:00 2024-04-08 14:54:03.490 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2800 2024-04-08 14:54:03.490 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:54:03.926 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:54:03.926 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:54:03.927 T:830 debug : CecLogMessage - TV (0): power status changed from 'standby' to 'on' 2024-04-08 14:54:03.927 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:03.927 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:03.927 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:54:04.037 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:54:05.108 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:54:05.108 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:54:05.108 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:05.108 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:05.108 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:54:05.219 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:54:06.436 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:54:06.436 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:54:06.436 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:06.436 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:06.436 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:54:06.547 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 # manually turn tv back on, nothing else happens, black screen 2024-04-08 14:54:20.962 T:796 debug : ------ Window Init () ------ 2024-04-08 14:54:53.395 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0 2024-04-08 14:54:53.396 T:830 debug : CecLogMessage - >> 0f:a0:00:80:45:20:00:00 2024-04-08 14:54:53.396 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:54:53.396 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 2024-04-08 14:54:53.747 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:54:53.747 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:53.747 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:53.862 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:54:54.428 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=0f opcode=80 2024-04-08 14:54:54.428 T:830 debug : CecLogMessage - >> 0f:80:00:00:20:00 2024-04-08 14:54:54.428 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): routing change (80) 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=81 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - >> 5f:81:25:00 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:54:54.542 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing information (81) 2024-04-08 14:54:54.605 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:54:54.605 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:54.605 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:54.720 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:54:54.720 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:54:54.720 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:54:54.811 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - >> 0f:86:25:00 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2500 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:55.445 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:54:55.556 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:54:55.559 T:796 debug : ------ Window Deinit () ------ 2024-04-08 14:54:57.104 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:54:57.104 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:54:57.104 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:54:57.346 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:54:57.346 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:54:57.346 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:54:58.076 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=83 2024-04-08 14:54:58.076 T:830 debug : CecLogMessage - >> 04:83 2024-04-08 14:54:58.076 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:54:58.076 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:54:58.076 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give physical address (83) 2024-04-08 14:54:58.211 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:54:58.306 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=8c 2024-04-08 14:54:58.306 T:830 debug : CecLogMessage - >> 04:8c 2024-04-08 14:54:58.306 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): vendor id Panasonic (8045) 2024-04-08 14:54:58.306 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:54:58.306 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): give device vendor id (8C) 2024-04-08 14:54:58.441 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:54:58.612 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=04 opcode=89 2024-04-08 14:54:58.612 T:830 debug : CecLogMessage - >> 04:89:10:01:05 2024-04-08 14:54:58.612 T:830 debug : CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 2024-04-08 14:54:58.612 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command (89) 2024-04-08 14:54:58.963 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89 2024-04-08 14:54:58.963 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:54:58.963 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:54:58.963 T:830 debug : CecLogMessage - scheduling active source message for 'Playback 1' 2024-04-08 14:54:59.192 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:54:59.192 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:54:59.192 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:54:59.192 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:54:59.192 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:54:59.303 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:00.303 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:55:00.303 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:55:00.356 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:55:00.356 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:00.356 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:00.542 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:55:00.653 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:00.653 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:55:00.653 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:55:00.744 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:55:00.744 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:55:00.744 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:00.744 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:00.744 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:55:00.859 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:02.066 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:55:02.066 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:55:02.066 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:02.066 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:02.066 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:55:02.177 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:03.413 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=84 2024-04-08 14:55:03.413 T:830 debug : CecLogMessage - >> 5f:84:20:00:05 2024-04-08 14:55:03.413 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): report physical address (84) 2024-04-08 14:55:03.655 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:55:03.655 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:55:03.655 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # select another input on avr, which turns it on and shows "Denon" logo (i.e. switched to a "no signal" input 2024-04-08 14:55:35.073 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:55:37.085 T:830 debug : CecLogMessage - changing physical address to FFFF 2024-04-08 14:55:37.086 T:830 debug : CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff 2024-04-08 14:55:37.086 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:55:37.105 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:55:37.269 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:55:37.270 T:830 debug : CecLogMessage - changing physical address to 2500 2024-04-08 14:55:37.270 T:830 debug : CecLogMessage - marking Playback 1 (4) as inactive source 2024-04-08 14:55:37.270 T:830 debug : CecLogMessage - >> source deactivated: Playback 1 (4) 2024-04-08 14:55:37.270 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:55:37.270 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:55:37.720 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:55:37.720 T:830 debug : CecLogMessage - making Playback 1 (4) the active source 2024-04-08 14:55:37.720 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:55:37.720 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:55:37.720 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:55:37.720 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:55:37.824 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:55:37.824 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:37.824 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:37.824 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:55:37.824 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:37.824 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:55:37.824 T:830 debug : CecLogMessage - failed to make 'Playback 1' the active source. will retry later 2024-04-08 14:55:37.825 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:55:37.830 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:55:38.139 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:55:38.349 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:55:38.808 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:55:38.808 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:55:38.808 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:38.808 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:38.808 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:55:38.919 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:39.057 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:55:39.057 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:55:39.057 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 2024-04-08 14:55:39.216 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=84 2024-04-08 14:55:39.216 T:830 debug : CecLogMessage - >> 3f:84:20:00:03 2024-04-08 14:55:39.216 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): report physical address (84) 2024-04-08 14:55:39.375 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:55:39.375 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:55:39.375 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:55:39.375 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:55:39.375 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:55:39.438 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:55:39.438 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:39.438 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:39.553 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:39.553 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:55:39.553 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:55:39.644 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:55:39.830 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:55:39.830 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:55:39.830 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:39.830 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:39.830 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:55:39.941 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:40.055 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=5f opcode=a7 2024-04-08 14:55:40.055 T:830 debug : CecLogMessage - >> 5f:a7:00:00 2024-04-08 14:55:40.055 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): UNKNOWN (A7) 2024-04-08 14:55:40.141 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=5f opcode=85 2024-04-08 14:55:40.141 T:830 debug : CecLogMessage - >> 5f:85 2024-04-08 14:55:40.141 T:830 debug : CecLogMessage - >> 5 requests active source 2024-04-08 14:55:40.141 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:40.141 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:40.141 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): request active source (85) 2024-04-08 14:55:40.252 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:40.434 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=5f opcode=80 2024-04-08 14:55:40.434 T:830 debug : CecLogMessage - >> 5f:80:25:00:24:00 2024-04-08 14:55:40.434 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing change (80) 2024-04-08 14:55:40.668 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:55:40.668 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:55:40.668 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:55:40.668 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:55:40.668 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:55:40.803 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:55:41.087 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:55:41.087 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:55:41.087 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:55:41.087 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:55:41.087 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:55:41.198 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:55:41.343 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86 2024-04-08 14:55:41.344 T:830 debug : CecLogMessage - >> 0f:86:24:00 2024-04-08 14:55:41.344 T:830 debug : CecLogMessage - >> TV (0) sets stream path to physical address 2400 2024-04-08 14:55:41.344 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 2024-04-08 14:55:42.164 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:55:42.164 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:55:42.164 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:55:43.035 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:55:43.035 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:55:43.035 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) # manually switch avr to kodi input, black screen 2024-04-08 14:56:44.388 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:56:44.849 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:56:44.983 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:56:45.447 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:56:45.447 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:56:45.447 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:56:45.447 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:56:45.447 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:56:45.558 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:56:46.777 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:56:46.777 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:56:46.777 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:56:46.777 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:56:46.777 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:56:46.888 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:56:48.106 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:56:48.106 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:56:48.106 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:56:48.106 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:56:48.106 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:56:48.217 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 # pressing up down on tv remote 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=04 opcode=44 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - >> 04:44:01 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - key pressed: up (1) current(ff) duration(0) 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - Changed key up (1) D:0ms cur:ff 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - key pressed: up (1, 0) 2024-04-08 14:57:08.212 T:830 debug : PushCecKeypress - received key a6 duration 0 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - CheckKeypressTimeout T:1571.821 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - Key up: idle (duration:0) (1) timeout:-631956640ms (rel:500,rep:0,prs:500,rel:0) 2024-04-08 14:57:08.212 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 2024-04-08 14:57:08.218 T:796 debug : HandleKey: 166 (0xa6, obc89) pressed, window 10016, action is Up 2024-04-08 14:57:08.316 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=45 2024-04-08 14:57:08.316 T:830 debug : CecLogMessage - >> 04:45 2024-04-08 14:57:08.316 T:830 debug : CecLogMessage - key released: up (1) D:104ms 2024-04-08 14:57:08.316 T:830 debug : PushCecKeypress - received key a6 duration 104 2024-04-08 14:57:08.316 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) 2024-04-08 14:57:09.251 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=04 opcode=44 2024-04-08 14:57:09.251 T:830 debug : CecLogMessage - >> 04:44:02 2024-04-08 14:57:09.251 T:830 debug : CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2024-04-08 14:57:09.251 T:830 debug : CecLogMessage - key pressed: down (2) current(ff) duration(0) 2024-04-08 14:57:09.251 T:830 debug : CecLogMessage - Changed key down (2) D:0ms cur:ff 2024-04-08 14:57:09.251 T:830 debug : CecLogMessage - key pressed: down (2, 0) 2024-04-08 14:57:09.252 T:830 debug : PushCecKeypress - received key a7 duration 0 2024-04-08 14:57:09.252 T:830 debug : CecLogMessage - CheckKeypressTimeout T:1572.860 2024-04-08 14:57:09.252 T:830 debug : CecLogMessage - Key down: idle (duration:0) (2) timeout:-631956640ms (rel:500,rep:0,prs:500,rel:0) 2024-04-08 14:57:09.252 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control pressed (44) 2024-04-08 14:57:09.262 T:796 debug : HandleKey: 167 (0xa7, obc88) pressed, window 10016, action is Down 2024-04-08 14:57:09.345 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=04 opcode=45 2024-04-08 14:57:09.345 T:830 debug : CecLogMessage - >> 04:45 2024-04-08 14:57:09.345 T:830 debug : CecLogMessage - key released: down (2) D:94ms 2024-04-08 14:57:09.345 T:830 debug : PushCecKeypress - received key a7 duration 94 2024-04-08 14:57:09.345 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45) # switch tv off, avr auto off, then auto tv + avr on with kodi input selected 2024-04-08 14:57:29.087 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:57:30.838 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:57:31.022 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:57:31.582 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:57:31.582 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:57:31.582 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:31.582 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:31.582 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:57:31.693 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:32.647 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:57:32.647 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:57:32.647 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:32.647 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:32.647 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:57:32.757 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:32.930 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:57:32.930 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:57:32.930 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:57:32.930 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:57:32.930 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:57:33.065 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:57:33.607 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:57:33.607 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:57:33.607 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:33.607 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:33.607 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:57:33.718 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=5f opcode=80 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - >> 5f:80:28:00:25:00 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - Playback 1 (4) was already marked as active source 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:57:34.304 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): routing change (80) 2024-04-08 14:57:34.367 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:57:34.367 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:34.367 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:34.460 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:57:34.482 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:34.482 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:57:34.482 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:57:34.482 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:57:34.482 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:57:34.482 T:830 error : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT failed - tx_status=00 errno=64 2024-04-08 14:57:35.097 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:57:35.182 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:57:36.160 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:57:36.160 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:57:36.487 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:57:36.498 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:57:38.510 T:830 debug : CecLogMessage - changing physical address to FFFF 2024-04-08 14:57:38.510 T:830 debug : CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff 2024-04-08 14:57:38.510 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:57:38.903 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:57:39.079 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:57:39.079 T:830 debug : CecLogMessage - changing physical address to 2500 2024-04-08 14:57:39.079 T:830 debug : CecLogMessage - marking Playback 1 (4) as inactive source 2024-04-08 14:57:39.079 T:830 debug : CecLogMessage - >> source deactivated: Playback 1 (4) 2024-04-08 14:57:39.079 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2500 2024-04-08 14:57:39.079 T:830 debug : CecLogMessage - << 4f:84:25:00:04 2024-04-08 14:57:39.571 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84 2024-04-08 14:57:39.571 T:830 debug : CecLogMessage - making Playback 1 (4) the active source 2024-04-08 14:57:39.571 T:830 debug : CecLogMessage - >> source activated: Playback 1 (4) 2024-04-08 14:57:39.574 T:830 debug : CecLogMessage - sending active source message for 'Playback 1' 2024-04-08 14:57:39.575 T:830 debug : CecLogMessage - << powering on 'TV' (0) 2024-04-08 14:57:39.575 T:830 debug : CecLogMessage - << 40:04 2024-04-08 14:57:39.820 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04 2024-04-08 14:57:39.820 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:39.820 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:39.935 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:39.935 T:830 debug : CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated' 2024-04-08 14:57:39.935 T:830 debug : CecLogMessage - << 40:8e:00 2024-04-08 14:57:40.088 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e 2024-04-08 14:57:40.088 T:830 debug : SetConfigurationFromLibCEC - settings updated by libCEC 2024-04-08 14:57:40.093 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:57:40.093 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:57:40.093 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:40.093 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:40.093 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:57:40.203 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:40.946 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:57:40.947 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:57:40.947 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:40.947 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:40.947 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:57:41.057 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:41.556 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:57:42.186 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:57:42.324 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff 2024-04-08 14:57:42.845 T:830 info : Skipped 1 duplicate messages.. 2024-04-08 14:57:42.845 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2500 2024-04-08 14:57:43.053 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2500 2024-04-08 14:57:43.373 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0 2024-04-08 14:57:43.373 T:830 debug : CecLogMessage - >> 04:a0:00:80:45:06:05 2024-04-08 14:57:43.373 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:43.373 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:43.373 T:830 debug : CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0) 2024-04-08 14:57:43.623 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:43.689 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=5f opcode=85 2024-04-08 14:57:43.689 T:830 debug : CecLogMessage - >> 5f:85 2024-04-08 14:57:43.689 T:830 debug : CecLogMessage - >> 5 requests active source 2024-04-08 14:57:43.689 T:830 debug : CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2500) 2024-04-08 14:57:43.689 T:830 debug : CecLogMessage - << 4f:82:25:00 2024-04-08 14:57:43.689 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): request active source (85) 2024-04-08 14:57:43.800 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82 2024-04-08 14:57:44.033 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:57:44.034 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:57:44.034 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:57:44.034 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:57:44.034 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:57:44.168 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87 2024-04-08 14:57:44.596 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=3f opcode=87 2024-04-08 14:57:44.596 T:830 debug : CecLogMessage - >> 3f:87:00:80:45 2024-04-08 14:57:44.596 T:830 debug : CecLogMessage - >> Tuner 1 (3) -> Broadcast (F): device vendor id (87) 2024-04-08 14:57:45.542 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=5f opcode=87 2024-04-08 14:57:45.542 T:830 debug : CecLogMessage - >> 5f:87:00:80:45 2024-04-08 14:57:45.542 T:830 debug : CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 2024-04-08 14:57:46.109 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=5 addr=0f opcode=87 2024-04-08 14:57:46.109 T:830 debug : CecLogMessage - >> 0f:87:00:80:45 2024-04-08 14:57:46.109 T:830 debug : CecLogMessage - << Playback 1 (4) -> Broadcast (F): vendor id Panasonic (8045) 2024-04-08 14:57:46.109 T:830 debug : CecLogMessage - << 4f:87:00:80:45 2024-04-08 14:57:46.109 T:830 debug : CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2024-04-08 14:57:46.244 T:830 debug : CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=87