2020-04-01 17:45:45.783 T:1036  NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
2020-04-01 17:45:45.783 T:1036  NOTICE: Enabled debug logging due to GUI setting. Level 2.
2020-04-01 17:45:45.785 T:1036   DEBUG: ------ Window Init () ------
2020-04-01 17:46:05.923 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8c
2020-04-01 17:46:05.923 T:1065   DEBUG: CecLogMessage - >> 01:8c
2020-04-01 17:46:05.923 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): vendor id Pulse Eight (1582)
2020-04-01 17:46:05.923 T:1065   DEBUG: CecLogMessage - << 1f:87:00:15:82
2020-04-01 17:46:05.923 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device vendor id (8C)
2020-04-01 17:46:06.072 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=87
2020-04-01 17:46:06.156 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=83
2020-04-01 17:46:06.157 T:1065   DEBUG: CecLogMessage - >> 01:83
2020-04-01 17:46:06.157 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2020-04-01 17:46:06.157 T:1065   DEBUG: CecLogMessage - << 1f:84:10:00:01
2020-04-01 17:46:06.157 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
2020-04-01 17:46:06.292 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=84
2020-04-01 17:46:07.651 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=46
2020-04-01 17:46:07.652 T:1065   DEBUG: CecLogMessage - >> 01:46
2020-04-01 17:46:07.653 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
2020-04-01 17:46:07.655 T:1065   DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
2020-04-01 17:46:07.658 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
2020-04-01 17:46:07.933 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=6 addr=10 opcode=47
2020-04-01 17:46:09.542 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=9f
2020-04-01 17:46:09.542 T:1065   DEBUG: CecLogMessage - >> 01:9f
2020-04-01 17:46:09.542 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): cec version 1.4
2020-04-01 17:46:09.542 T:1065   DEBUG: CecLogMessage - << 10:9e:05
2020-04-01 17:46:09.542 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): get cec version (9F)
2020-04-01 17:46:09.634 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=9e
2020-04-01 17:46:21.546 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:46:21.547 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:46:21.547 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:46:21.547 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:46:21.547 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:46:21.639 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:46:23.756 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:46:23.756 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:46:23.756 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:46:23.757 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:46:23.757 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:46:23.847 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:46:23.935 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=83
2020-04-01 17:46:23.936 T:1065   DEBUG: CecLogMessage - >> 01:83
2020-04-01 17:46:23.937 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2020-04-01 17:46:23.937 T:1065   DEBUG: CecLogMessage - << 1f:84:10:00:01
2020-04-01 17:46:23.937 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
2020-04-01 17:46:24.071 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=84
2020-04-01 17:46:28.216 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:46:28.217 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:46:28.217 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:46:28.217 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:46:28.217 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:46:28.347 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=3 addr=10 opcode=90
2020-04-01 17:46:32.514 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:46:32.521 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:46:32.523 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:46:32.523 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:46:32.523 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:46:32.602 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:46:34.689 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:46:34.690 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:46:34.690 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:46:34.690 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:46:34.690 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:46:34.782 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:46:35.054 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86
2020-04-01 17:46:35.054 T:1065   DEBUG: CecLogMessage - >> 0f:86:10:00
2020-04-01 17:46:35.054 T:1065   DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1000
2020-04-01 17:46:35.054 T:1065   DEBUG: CecLogMessage - making Recorder 1 (1) the active source
2020-04-01 17:46:35.055 T:1065   DEBUG: CecLogMessage - marking TV (0) as inactive source
2020-04-01 17:46:35.055 T:1065   DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
2020-04-01 17:46:35.055 T:1065   DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
2020-04-01 17:46:35.055 T:1065   DEBUG: CecLogMessage - << powering on 'TV' (0)
2020-04-01 17:46:35.055 T:1065   DEBUG: CecLogMessage - << 10:04
2020-04-01 17:46:35.055 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
2020-04-01 17:46:35.122 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=10 opcode=04
2020-04-01 17:46:35.122 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000)
2020-04-01 17:46:35.122 T:1065   DEBUG: CecLogMessage - << 1f:82:10:00
2020-04-01 17:46:35.243 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=1f opcode=82
2020-04-01 17:46:35.243 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
2020-04-01 17:46:35.243 T:1065   DEBUG: CecLogMessage - << 10:8e:00
2020-04-01 17:46:35.348 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=8e
2020-04-01 17:46:37.266 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:37.266 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:37.266 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:37.266 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:37.266 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:37.266 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:37.267 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:37.267 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2378.757
2020-04-01 17:46:37.267 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:46:37.267 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:37.273 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:37.566 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:46:37.567 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:46:37.567 T:1065   DEBUG: CecLogMessage - key released: down (2) D:300ms
2020-04-01 17:46:37.567 T:1065   DEBUG: PushCecKeypress - received key a7 duration 300
2020-04-01 17:46:37.567 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:46:38.074 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:38.075 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2379.565
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:46:38.075 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:38.124 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:38.216 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:46:38.216 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:46:38.216 T:1065   DEBUG: CecLogMessage - key released: down (2) D:142ms
2020-04-01 17:46:38.216 T:1065   DEBUG: PushCecKeypress - received key a7 duration 142
2020-04-01 17:46:38.217 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:46:38.844 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:38.845 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2380.335
2020-04-01 17:46:38.845 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:46:38.846 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:38.865 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:39.125 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=65
2020-04-01 17:46:39.133 T:1065   DEBUG: CecLogMessage - >> 01:65
2020-04-01 17:46:39.133 T:1065   DEBUG: CecLogMessage - sending abort with opcode 65 and reason 'unrecognised opcode' to TV
2020-04-01 17:46:39.133 T:1065   DEBUG: CecLogMessage - << transmitting abort message
2020-04-01 17:46:39.133 T:1065   DEBUG: CecLogMessage - << 10:00:65:00
2020-04-01 17:46:39.133 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): UNKNOWN (65)
2020-04-01 17:46:39.237 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=10 opcode=00
2020-04-01 17:46:39.237 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2380.727
2020-04-01 17:46:39.237 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:108,rep:0,prs:108,rel:0)
2020-04-01 17:46:39.346 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2380.836
2020-04-01 17:46:39.346 T:1065   DEBUG: CecLogMessage - Key unknown: idle (duration:501) (ff) timeout:-1226368624ms (rel:1000,rep:0,prs:0,rel:0)
2020-04-01 17:46:39.585 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:39.585 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:39.585 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:39.585 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:39.585 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:39.586 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:39.586 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:39.586 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2381.076
2020-04-01 17:46:39.586 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:46:39.586 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:39.592 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:39.802 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:46:39.802 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:46:39.806 T:1065   DEBUG: CecLogMessage - key released: down (2) D:217ms
2020-04-01 17:46:39.806 T:1065   DEBUG: PushCecKeypress - received key a7 duration 217
2020-04-01 17:46:39.806 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:46:40.444 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:40.450 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:40.451 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2381.937
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:499,rep:0,prs:499,rel:0)
2020-04-01 17:46:40.451 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:40.514 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:40.950 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2382.440
2020-04-01 17:46:40.950 T:1065   DEBUG: CecLogMessage - Key unknown: idle (duration:504) (ff) timeout:-1226368624ms (rel:1000,rep:0,prs:0,rel:0)
2020-04-01 17:46:41.466 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:41.466 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:41.466 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:41.466 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:41.467 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:41.467 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:41.467 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:41.467 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2382.957
2020-04-01 17:46:41.467 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:46:41.467 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:41.474 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:41.740 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:46:41.740 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:46:41.740 T:1065   DEBUG: CecLogMessage - key released: down (2) D:274ms
2020-04-01 17:46:41.740 T:1065   DEBUG: PushCecKeypress - received key a7 duration 274
2020-04-01 17:46:41.741 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:46:42.556 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:46:42.556 T:1065   DEBUG: CecLogMessage - >> 01:44:02
2020-04-01 17:46:42.556 T:1065   DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2
2020-04-01 17:46:42.556 T:1065   DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0)
2020-04-01 17:46:42.556 T:1065   DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff
2020-04-01 17:46:42.556 T:1065   DEBUG: CecLogMessage - key pressed: down (2, 0)
2020-04-01 17:46:42.556 T:1065   DEBUG: PushCecKeypress - received key a7 duration 0
2020-04-01 17:46:42.557 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2384.046
2020-04-01 17:46:42.557 T:1065   DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:46:42.557 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:46:42.559 T:1036   DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down
2020-04-01 17:46:43.056 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2384.547
2020-04-01 17:46:43.056 T:1065   DEBUG: CecLogMessage - Key unknown: idle (duration:501) (ff) timeout:-1226368624ms (rel:1000,rep:0,prs:0,rel:0)
2020-04-01 17:46:44.164 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=82
2020-04-01 17:46:44.164 T:1065   DEBUG: CecLogMessage - >> 0f:82:00:00
2020-04-01 17:46:44.164 T:1065   DEBUG: CecLogMessage - making TV (0) the active source
2020-04-01 17:46:44.164 T:1065   DEBUG: CecLogMessage - marking Recorder 1 (1) as inactive source
2020-04-01 17:46:44.164 T:1065   DEBUG: CecLogMessage - >> source deactivated: Recorder 1 (1)
2020-04-01 17:46:44.177 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): active source (82)
2020-04-01 17:46:56.235 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8c
2020-04-01 17:46:56.235 T:1065   DEBUG: CecLogMessage - >> 01:8c
2020-04-01 17:46:56.235 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): vendor id Pulse Eight (1582)
2020-04-01 17:46:56.235 T:1065   DEBUG: CecLogMessage - << 1f:87:00:15:82
2020-04-01 17:46:56.235 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device vendor id (8C)
2020-04-01 17:46:56.375 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=87
2020-04-01 17:46:58.018 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=46
2020-04-01 17:46:58.018 T:1065   DEBUG: CecLogMessage - >> 01:46
2020-04-01 17:46:58.018 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
2020-04-01 17:46:58.018 T:1065   DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
2020-04-01 17:46:58.018 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
2020-04-01 17:46:58.187 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=6 addr=10 opcode=47
2020-04-01 17:46:59.822 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=9f
2020-04-01 17:46:59.825 T:1065   DEBUG: CecLogMessage - >> 01:9f
2020-04-01 17:46:59.825 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): cec version 1.4
2020-04-01 17:46:59.825 T:1065   DEBUG: CecLogMessage - << 10:9e:05
2020-04-01 17:46:59.825 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): get cec version (9F)
2020-04-01 17:46:59.967 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=3 addr=10 opcode=9e
2020-04-01 17:47:10.122 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:47:10.122 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:47:10.122 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:47:10.122 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:47:10.123 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:47:10.217 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:47:10.300 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=83
2020-04-01 17:47:10.300 T:1065   DEBUG: CecLogMessage - >> 01:83
2020-04-01 17:47:10.300 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2020-04-01 17:47:10.300 T:1065   DEBUG: CecLogMessage - << 1f:84:10:00:01
2020-04-01 17:47:10.300 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
2020-04-01 17:47:10.440 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=84
2020-04-01 17:47:10.689 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86
2020-04-01 17:47:10.689 T:1065   DEBUG: CecLogMessage - >> 0f:86:10:00
2020-04-01 17:47:10.689 T:1065   DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1000
2020-04-01 17:47:10.689 T:1065   DEBUG: CecLogMessage - making Recorder 1 (1) the active source
2020-04-01 17:47:10.689 T:1065   DEBUG: CecLogMessage - marking TV (0) as inactive source
2020-04-01 17:47:10.690 T:1065   DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
2020-04-01 17:47:10.690 T:1065   DEBUG: CecLogMessage - sending active source message for 'Recorder 1'
2020-04-01 17:47:10.690 T:1065   DEBUG: CecLogMessage - << powering on 'TV' (0)
2020-04-01 17:47:10.690 T:1065   DEBUG: CecLogMessage - << 10:04
2020-04-01 17:47:10.690 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
2020-04-01 17:47:10.757 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=10 opcode=04
2020-04-01 17:47:10.757 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000)
2020-04-01 17:47:10.758 T:1065   DEBUG: CecLogMessage - << 1f:82:10:00
2020-04-01 17:47:10.878 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=1f opcode=82
2020-04-01 17:47:10.878 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated'
2020-04-01 17:47:10.879 T:1065   DEBUG: CecLogMessage - << 10:8e:00
2020-04-01 17:47:11.062 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=3 addr=10 opcode=8e
2020-04-01 17:47:12.546 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:47:12.546 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:47:12.546 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:47:12.547 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:47:12.547 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:47:12.718 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=3 addr=10 opcode=90
2020-04-01 17:47:16.811 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:47:16.811 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:47:16.811 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:47:16.811 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:47:16.811 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:47:16.903 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:47:17.037 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86
2020-04-01 17:47:17.038 T:1065   DEBUG: CecLogMessage - >> 0f:86:00:00
2020-04-01 17:47:17.038 T:1065   DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 0000
2020-04-01 17:47:17.038 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
2020-04-01 17:47:18.992 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:47:18.992 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:47:18.992 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:47:18.992 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:47:18.992 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:47:19.054 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:47:19.146 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:47:19.146 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:47:19.147 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:47:19.147 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:47:19.147 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:47:19.242 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:47:19.353 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86
2020-04-01 17:47:19.353 T:1065   DEBUG: CecLogMessage - >> 0f:86:10:00
2020-04-01 17:47:19.353 T:1065   DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1000
2020-04-01 17:47:19.353 T:1065   DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
2020-04-01 17:47:19.353 T:1065   DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
2020-04-01 17:47:19.354 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000)
2020-04-01 17:47:19.354 T:1065   DEBUG: CecLogMessage - << 1f:82:10:00
2020-04-01 17:47:19.354 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
2020-04-01 17:47:19.469 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=1f opcode=82
2020-04-01 17:47:22.673 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:47:22.673 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:47:22.673 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:47:48.338 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8c
2020-04-01 17:47:48.338 T:1065   DEBUG: CecLogMessage - >> 01:8c
2020-04-01 17:47:48.338 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): vendor id Pulse Eight (1582)
2020-04-01 17:47:48.338 T:1065   DEBUG: CecLogMessage - << 1f:87:00:15:82
2020-04-01 17:47:48.338 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device vendor id (8C)
2020-04-01 17:47:48.483 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=87
2020-04-01 17:47:48.562 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=83
2020-04-01 17:47:48.562 T:1065   DEBUG: CecLogMessage - >> 01:83
2020-04-01 17:47:48.562 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2020-04-01 17:47:48.562 T:1065   DEBUG: CecLogMessage - << 1f:84:10:00:01
2020-04-01 17:47:48.562 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
2020-04-01 17:47:48.702 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=84
2020-04-01 17:47:48.787 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=46
2020-04-01 17:47:48.788 T:1065   DEBUG: CecLogMessage - >> 01:46
2020-04-01 17:47:48.788 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
2020-04-01 17:47:48.788 T:1065   DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
2020-04-01 17:47:48.788 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
2020-04-01 17:47:48.952 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=6 addr=10 opcode=47
2020-04-01 17:47:49.291 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=6 addr=01 opcode=a0
2020-04-01 17:47:49.291 T:1065   DEBUG: CecLogMessage - >> 01:a0:00:00:f0:23
2020-04-01 17:47:49.291 T:1065   DEBUG: CecLogMessage - << 10:a0:00:00:f0:24:00:80
2020-04-01 17:47:49.291 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor command with id (A0)
2020-04-01 17:47:49.503 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=8 addr=10 opcode=a0
2020-04-01 17:47:49.686 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=9b
2020-04-01 17:47:49.686 T:1065   DEBUG: CecLogMessage - >> 01:9b
2020-04-01 17:47:49.687 T:1065   DEBUG: CecLogMessage - sending abort with opcode 9b and reason 'unrecognised opcode' to TV
2020-04-01 17:47:49.687 T:1065   DEBUG: CecLogMessage - << transmitting abort message
2020-04-01 17:47:49.687 T:1065   DEBUG: CecLogMessage - << 10:00:9b:00
2020-04-01 17:47:49.687 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): UNKNOWN (9B)
2020-04-01 17:47:49.802 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=10 opcode=00
2020-04-01 17:48:15.710 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=85
2020-04-01 17:48:15.710 T:1065   DEBUG: CecLogMessage - >> 0f:85
2020-04-01 17:48:15.710 T:1065   DEBUG: CecLogMessage - >> 0 requests active source
2020-04-01 17:48:15.711 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000)
2020-04-01 17:48:15.711 T:1065   DEBUG: CecLogMessage - << 1f:82:10:00
2020-04-01 17:48:15.711 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): request active source (85)
2020-04-01 17:48:15.827 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=1f opcode=82
2020-04-01 17:48:16.430 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=21 opcode=1a
2020-04-01 17:48:16.430 T:1065   DEBUG: CecLogMessage - >> 21:1a:01
2020-04-01 17:48:16.430 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> Recorder 2 (2): deck status 'stop'
2020-04-01 17:48:16.430 T:1065   DEBUG: CecLogMessage - not sending command 'deck status': destination device 'Recorder 2' marked as not present
2020-04-01 17:48:16.431 T:1065   DEBUG: CecLogMessage - device Recorder 2 (2) status changed to present after command give deck status
2020-04-01 17:48:16.431 T:1065   DEBUG: CecLogMessage - >> Recorder 2 (2) -> Recorder 1 (1): give deck status (1A)
2020-04-01 17:48:26.716 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:48:26.717 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:48:26.717 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:48:26.717 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:48:26.717 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:48:26.804 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:48:26.892 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8c
2020-04-01 17:48:26.893 T:1065   DEBUG: CecLogMessage - >> 01:8c
2020-04-01 17:48:26.893 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): vendor id Pulse Eight (1582)
2020-04-01 17:48:26.893 T:1065   DEBUG: CecLogMessage - << 1f:87:00:15:82
2020-04-01 17:48:26.893 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device vendor id (8C)
2020-04-01 17:48:27.033 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=87
2020-04-01 17:48:28.470 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=21 opcode=83
2020-04-01 17:48:28.470 T:1065   DEBUG: CecLogMessage - >> 21:83
2020-04-01 17:48:28.470 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2020-04-01 17:48:28.470 T:1065   DEBUG: CecLogMessage - << 1f:84:10:00:01
2020-04-01 17:48:28.471 T:1065   DEBUG: CecLogMessage - >> Recorder 2 (2) -> Recorder 1 (1): give physical address (83)
2020-04-01 17:48:28.610 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=84
2020-04-01 17:48:28.908 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=46
2020-04-01 17:48:28.913 T:1065   DEBUG: CecLogMessage - >> 01:46
2020-04-01 17:48:28.917 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): OSD name 'Kodi'
2020-04-01 17:48:28.921 T:1065   DEBUG: CecLogMessage - << 10:47:4b:6f:64:69
2020-04-01 17:48:28.924 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give osd name (46)
2020-04-01 17:48:29.332 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=6 addr=10 opcode=47
2020-04-01 17:48:30.913 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=9f
2020-04-01 17:48:30.913 T:1065   DEBUG: CecLogMessage - >> 01:9f
2020-04-01 17:48:30.914 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): cec version 1.4
2020-04-01 17:48:30.914 T:1065   DEBUG: CecLogMessage - << 10:9e:05
2020-04-01 17:48:30.914 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): get cec version (9F)
2020-04-01 17:48:31.005 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=9e
2020-04-01 17:48:42.079 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:48:42.079 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:48:42.079 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:48:42.079 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:48:42.079 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:48:42.248 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=05 len=3 addr=10 opcode=90
2020-04-01 17:48:44.362 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=8f
2020-04-01 17:48:44.365 T:1065   DEBUG: CecLogMessage - >> 01:8f
2020-04-01 17:48:44.365 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): on
2020-04-01 17:48:44.365 T:1065   DEBUG: CecLogMessage - << 10:90:00
2020-04-01 17:48:44.365 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give device power status (8F)
2020-04-01 17:48:44.448 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=90
2020-04-01 17:48:44.592 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=83
2020-04-01 17:48:44.592 T:1065   DEBUG: CecLogMessage - >> 01:83
2020-04-01 17:48:44.592 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): physical address 1000
2020-04-01 17:48:44.592 T:1065   DEBUG: CecLogMessage - << 1f:84:10:00:01
2020-04-01 17:48:44.593 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give physical address (83)
2020-04-01 17:48:44.737 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=1f opcode=84
2020-04-01 17:48:45.021 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=4 addr=0f opcode=86
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - >> 0f:86:10:00
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1000
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - >> source activated: Recorder 1 (1)
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000)
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - << 1f:82:10:00
2020-04-01 17:48:45.022 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86)
2020-04-01 17:48:45.138 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=1f opcode=82
2020-04-01 17:48:45.245 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=1a
2020-04-01 17:48:45.245 T:1065   DEBUG: CecLogMessage - >> 01:1a:01
2020-04-01 17:48:45.245 T:1065   DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): deck status 'stop'
2020-04-01 17:48:45.245 T:1065   DEBUG: CecLogMessage - << 10:1b:1a
2020-04-01 17:48:45.245 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): give deck status (1A)
2020-04-01 17:48:45.339 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=10 opcode=1b
2020-04-01 17:48:45.936 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - >> 01:44:01
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - key pressed: up (1, 0)
2020-04-01 17:48:45.937 T:1065   DEBUG: PushCecKeypress - received key a6 duration 0
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2507.427
2020-04-01 17:48:45.937 T:1065   DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:45.938 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:45.940 T:1036   DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2020-04-01 17:48:45.941 T:1044    INFO: CActiveAESink::OpenSink - initialize sink
2020-04-01 17:48:45.941 T:1044   DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=allwinnerhdmi,DEV=0
2020-04-01 17:48:45.942 T:1044    INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=allwinnerhdmi,DEV=0"
2020-04-01 17:48:45.955 T:1044    INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=allwinnerhdmi,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00"
2020-04-01 17:48:45.957 T:1044    INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2020-04-01 17:48:45.958 T:1044    INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE4
2020-04-01 17:48:45.958 T:1044   DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
2020-04-01 17:48:45.967 T:1044   DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
2020-04-01 17:48:45.967 T:1044   DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2020-04-01 17:48:45.967 T:1044   DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2020-04-01 17:48:45.967 T:1044   DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2020-04-01 17:48:45.967 T:1044   DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2020-04-01 17:48:45.967 T:1044   DEBUG: CActiveAESink::OpenSink - ALSA Initialized:
2020-04-01 17:48:45.967 T:1044   DEBUG:   Output Device : allwinner-hdmi
2020-04-01 17:48:45.967 T:1044   DEBUG:   Sample Rate   : 44100
2020-04-01 17:48:45.967 T:1044   DEBUG:   Sample Format : AE_FMT_S24NE4
2020-04-01 17:48:45.967 T:1044   DEBUG:   Channel Count : 2
2020-04-01 17:48:45.968 T:1044   DEBUG:   Channel Layout: FL, FR
2020-04-01 17:48:45.968 T:1044   DEBUG:   Frames        : 2205
2020-04-01 17:48:45.968 T:1044   DEBUG:   Frame Size    : 8
2020-04-01 17:48:46.437 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2507.927
2020-04-01 17:48:46.437 T:1065   DEBUG: CecLogMessage - Key unknown: idle (duration:500) (ff) timeout:-1226368624ms (rel:1000,rep:0,prs:0,rel:0)
2020-04-01 17:48:46.640 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:46.640 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:46.640 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:46.940 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:46.940 T:1065   DEBUG: CecLogMessage - >> 01:44:11
2020-04-01 17:48:46.940 T:1065   DEBUG: CecLogMessage - SetCurrentButton dvd menu (11) D:0ms cur:11
2020-04-01 17:48:46.941 T:1065   DEBUG: CecLogMessage - key pressed: dvd menu (11) current(ff) duration(0)
2020-04-01 17:48:46.941 T:1065   DEBUG: CecLogMessage - Changed key dvd menu (11) D:0ms cur:ff
2020-04-01 17:48:46.941 T:1065   DEBUG: CecLogMessage - key pressed: dvd menu (11, 0)
2020-04-01 17:48:46.941 T:1065   DEBUG: PushCecKeypress - received key ef duration 0
2020-04-01 17:48:46.941 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2508.431
2020-04-01 17:48:46.941 T:1065   DEBUG: CecLogMessage - Key dvd menu: idle (duration:0) (11) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:46.941 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:46.982 T:1036   DEBUG: HandleKey: 239 (0xef, obc16) pressed, action is
2020-04-01 17:48:47.012 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:47.012 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:47.012 T:1065   DEBUG: CecLogMessage - key released: dvd menu (11) D:72ms
2020-04-01 17:48:47.013 T:1065   DEBUG: PushCecKeypress - received key ef duration 72
2020-04-01 17:48:47.013 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:47.364 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:47.364 T:1065   DEBUG: CecLogMessage - >> 01:44:01
2020-04-01 17:48:47.364 T:1065   DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
2020-04-01 17:48:47.365 T:1065   DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
2020-04-01 17:48:47.365 T:1065   DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
2020-04-01 17:48:47.365 T:1065   DEBUG: CecLogMessage - key pressed: up (1, 0)
2020-04-01 17:48:47.365 T:1065   DEBUG: PushCecKeypress - received key a6 duration 0
2020-04-01 17:48:47.365 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2508.855
2020-04-01 17:48:47.365 T:1065   DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:47.365 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:47.407 T:1036   DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2020-04-01 17:48:47.484 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:47.485 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:47.485 T:1065   DEBUG: CecLogMessage - key released: up (1) D:120ms
2020-04-01 17:48:47.485 T:1065   DEBUG: PushCecKeypress - received key a6 duration 120
2020-04-01 17:48:47.485 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:47.635 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:47.638 T:1065   DEBUG: CecLogMessage - >> 01:44:01
2020-04-01 17:48:47.638 T:1065   DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
2020-04-01 17:48:47.638 T:1065   DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
2020-04-01 17:48:47.638 T:1065   DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
2020-04-01 17:48:47.638 T:1065   DEBUG: CecLogMessage - key pressed: up (1, 0)
2020-04-01 17:48:47.638 T:1065   DEBUG: PushCecKeypress - received key a6 duration 0
2020-04-01 17:48:47.639 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2509.128
2020-04-01 17:48:47.639 T:1065   DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:47.639 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:47.651 T:1036   DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2020-04-01 17:48:47.936 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:47.936 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:47.936 T:1065   DEBUG: CecLogMessage - key released: up (1) D:299ms
2020-04-01 17:48:47.937 T:1065   DEBUG: PushCecKeypress - received key a6 duration 299
2020-04-01 17:48:47.937 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:48.445 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:48.445 T:1065   DEBUG: CecLogMessage - >> 01:44:01
2020-04-01 17:48:48.445 T:1065   DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
2020-04-01 17:48:48.445 T:1065   DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
2020-04-01 17:48:48.445 T:1065   DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
2020-04-01 17:48:48.446 T:1065   DEBUG: CecLogMessage - key pressed: up (1, 0)
2020-04-01 17:48:48.446 T:1065   DEBUG: PushCecKeypress - received key a6 duration 0
2020-04-01 17:48:48.446 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2509.936
2020-04-01 17:48:48.446 T:1065   DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:48.446 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:48.533 T:1036   DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2020-04-01 17:48:48.639 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:48.639 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:48.639 T:1065   DEBUG: CecLogMessage - key released: up (1) D:194ms
2020-04-01 17:48:48.639 T:1065   DEBUG: PushCecKeypress - received key a6 duration 194
2020-04-01 17:48:48.639 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:49.275 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:49.275 T:1065   DEBUG: CecLogMessage - >> 01:44:01
2020-04-01 17:48:49.275 T:1065   DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
2020-04-01 17:48:49.275 T:1065   DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
2020-04-01 17:48:49.275 T:1065   DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
2020-04-01 17:48:49.275 T:1065   DEBUG: CecLogMessage - key pressed: up (1, 0)
2020-04-01 17:48:49.275 T:1065   DEBUG: PushCecKeypress - received key a6 duration 0
2020-04-01 17:48:49.276 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2510.766
2020-04-01 17:48:49.276 T:1065   DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:49.276 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:49.281 T:1036   DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2020-04-01 17:48:49.604 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:49.604 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:49.604 T:1065   DEBUG: CecLogMessage - key released: up (1) D:328ms
2020-04-01 17:48:49.604 T:1065   DEBUG: PushCecKeypress - received key a6 duration 328
2020-04-01 17:48:49.604 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:50.207 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=01 opcode=44
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - >> 01:44:01
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0)
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - key pressed: up (1, 0)
2020-04-01 17:48:50.208 T:1065   DEBUG: PushCecKeypress - received key a6 duration 0
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - CheckKeypressTimeout T:2511.698
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1226389940ms (rel:500,rep:0,prs:500,rel:0)
2020-04-01 17:48:50.208 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44)
2020-04-01 17:48:50.209 T:1036   DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up
2020-04-01 17:48:50.359 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:50.359 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:50.359 T:1065   DEBUG: CecLogMessage - key released: up (1) D:151ms
2020-04-01 17:48:50.359 T:1065   DEBUG: PushCecKeypress - received key a6 duration 151
2020-04-01 17:48:50.360 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)
2020-04-01 17:48:54.467 T:1065   DEBUG: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=01 opcode=45
2020-04-01 17:48:54.467 T:1065   DEBUG: CecLogMessage - >> 01:45
2020-04-01 17:48:54.467 T:1065   DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control release (45)