login as: root root@10.0.1.83's password: ############################################## # LibreELEC # # https://libreelec.tv # ############################################## LibreELEC (official): 9.2.6 (RPi4.arm) LibreELEC:~ # tail -f /storage/.kodi/temp/kodi.log 2021-01-12 14:54:47.051 T:2733892464 DEBUG: CecLogMessage - command 'give device power status' timeout 2021-01-12 14:54:47.551 T:2733892464 DEBUG: CecLogMessage - << 10:8f 2021-01-12 14:54:47.642 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=47 initiator=1 destination=0 response=0 2021-01-12 14:54:48.551 T:2733892464 DEBUG: CecLogMessage - command 'give device power status' timeout 2021-01-12 14:54:49.051 T:2725499760 DEBUG: OpenConnection - connection to the CEC adapter opened 2021-01-12 14:54:49.052 T:2733892464 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'unknown' 2021-01-12 14:54:49.052 T:2725499760 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC 2021-01-12 14:54:49.052 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:54:49.052 T:2675241840 DEBUG: Thread CECAdapterUpdate start, auto delete: false 2021-01-12 14:54:49.173 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8f initiator=1 destination=0 response=0 2021-01-12 14:54:50.052 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:54:50.552 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:54:50.703 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8f initiator=1 destination=0 response=0 2021-01-12 14:54:51.552 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:54:52.052 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:54:52.053 T:2733892464 DEBUG: CecLogMessage - Recorder 1 (1) was already marked as active source 2021-01-12 14:54:52.053 T:2733892464 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on' 2021-01-12 14:54:52.053 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:54:52.234 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:54:53.053 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:54:53.553 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:54:53.767 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:54:54.554 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:54:55.054 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:54:55.054 T:2733892464 DEBUG: CecLogMessage - >> source activated: Recorder 1 (1) 2021-01-12 14:54:55.054 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:54:55.054 T:2733892464 DEBUG: CecLogMessage - sending active source message for 'Recorder 1' 2021-01-12 14:54:55.054 T:2733892464 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0) 2021-01-12 14:54:55.299 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:54:56.054 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:54:56.554 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:54:56.830 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:54:57.555 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:54:58.001 T:2859860848 DEBUG: Thread JobWorker 2859860848 terminating (autodelete) 2021-01-12 14:54:58.001 T:2876646256 DEBUG: Thread JobWorker 2876646256 terminating (autodelete) 2021-01-12 14:54:58.001 T:2868253552 DEBUG: Thread JobWorker 2868253552 terminating (autodelete) 2021-01-12 14:54:58.055 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:54:58.055 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:54:58.361 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:54:59.055 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:54:59.555 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:54:59.892 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:00.555 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:55:01.056 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:55:01.056 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:01.056 T:2733892464 DEBUG: CecLogMessage - >> 01:90:00 2021-01-12 14:55:01.056 T:2733892464 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on' 2021-01-12 14:55:01.056 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): report power status (90) 2021-01-12 14:55:01.422 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:55:02.056 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:02.556 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:02.955 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:55:03.556 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:04.057 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:55:04.057 T:2733892464 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0) 2021-01-12 14:55:04.057 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:04.485 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:05.057 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:05.557 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:06.015 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:06.557 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:07.057 T:2675241840 DEBUG: UpdateMenuLanguage - using TV menu language is disabled 2021-01-12 14:55:07.058 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:55:07.058 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:55:07.545 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:08.058 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:55:08.558 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:55:09.076 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:09.558 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:55:10.058 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:55:10.059 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Recorder 2 (2): POLL 2021-01-12 14:55:10.059 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:10.607 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:55:11.059 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:11.559 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:12.143 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:55:12.559 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:13.059 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:55:13.060 T:2733892464 DEBUG: CecLogMessage - << 12 2021-01-12 14:55:13.674 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:14.060 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:14.560 T:2733892464 DEBUG: CecLogMessage - << 12 2021-01-12 14:55:15.204 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:15.560 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:16.060 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:16.060 T:2733892464 DEBUG: CecLogMessage - Recorder 2 (2): device status changed into 'not present' 2021-01-12 14:55:16.060 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:16.736 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=2 response=1 2021-01-12 14:55:17.062 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:17.563 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:18.267 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=2 response=1 2021-01-12 14:55:18.563 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:19.063 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:55:19.063 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Tuner 1 (3): POLL 2021-01-12 14:55:19.063 T:2733892464 DEBUG: CecLogMessage - << 13 2021-01-12 14:55:19.063 T:2733892464 DEBUG: CecLogMessage - >> 01:90:00 2021-01-12 14:55:19.063 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): report power status (90) 2021-01-12 14:55:19.802 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:20.063 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:20.563 T:2733892464 DEBUG: CecLogMessage - << 13 2021-01-12 14:55:21.333 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:21.564 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:22.064 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:22.064 T:2733892464 DEBUG: CecLogMessage - Tuner 1 (3): device status changed into 'not present' 2021-01-12 14:55:22.064 T:2733892464 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0) 2021-01-12 14:55:22.064 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Playback 1 (4): POLL 2021-01-12 14:55:22.064 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:55:22.866 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=3 response=1 2021-01-12 14:55:23.064 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:55:23.564 T:2733892464 DEBUG: CecLogMessage - << 10:8c 2021-01-12 14:55:24.397 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=3 response=1 2021-01-12 14:55:24.565 T:2733892464 DEBUG: CecLogMessage - command 'give device vendor id' timeout 2021-01-12 14:55:25.065 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:55:25.065 T:2733892464 DEBUG: CecLogMessage - << 14 2021-01-12 14:55:25.928 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:55:26.065 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:26.565 T:2733892464 DEBUG: CecLogMessage - << 14 2021-01-12 14:55:27.463 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8c initiator=1 destination=0 response=0 2021-01-12 14:55:27.566 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:28.066 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:28.066 T:2733892464 DEBUG: CecLogMessage - Playback 1 (4): device status changed into 'not present' 2021-01-12 14:55:28.066 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:28.994 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=4 response=1 2021-01-12 14:55:29.066 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:29.566 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:30.525 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=4 response=1 2021-01-12 14:55:30.566 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:31.067 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:55:31.067 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Audio (5): POLL 2021-01-12 14:55:31.067 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:32.055 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:32.068 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:32.568 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:33.568 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:33.585 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:34.068 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:55:34.068 T:2733892464 DEBUG: CecLogMessage - << 15 2021-01-12 14:55:34.819 T:2979001200 DEBUG: CAESinkPi:Drain delay:99ms now:0ms 2021-01-12 14:55:35.069 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:35.116 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:35.569 T:2733892464 DEBUG: CecLogMessage - << 15 2021-01-12 14:55:36.569 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:36.647 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:37.069 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:37.069 T:2733892464 DEBUG: CecLogMessage - Audio (5): device status changed into 'not present' 2021-01-12 14:55:37.069 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:55:37.069 T:2733892464 DEBUG: CecLogMessage - TV (0): vendor = Samsung (0000f0) 2021-01-12 14:55:37.070 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Tuner 2 (6): POLL 2021-01-12 14:55:37.070 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:55:37.070 T:2733892464 DEBUG: CecLogMessage - << 16 2021-01-12 14:55:37.070 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:55:38.070 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:38.177 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=5 response=1 2021-01-12 14:55:38.570 T:2733892464 DEBUG: CecLogMessage - << 16 2021-01-12 14:55:39.570 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:39.707 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=5 response=1 2021-01-12 14:55:40.070 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:40.070 T:2733892464 DEBUG: CecLogMessage - Tuner 2 (6): device status changed into 'not present' 2021-01-12 14:55:40.070 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:55:41.070 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:55:41.237 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=6 response=1 2021-01-12 14:55:41.571 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:55:42.571 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:55:42.768 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=6 response=1 2021-01-12 14:55:43.071 T:2733892464 DEBUG: CecLogMessage - replacing the command handler for device 'TV' (0) 2021-01-12 14:55:43.071 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:55:43.071 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Tuner 3 (7): POLL 2021-01-12 14:55:43.071 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:44.072 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:44.300 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:55:44.572 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:44.819 T:2979001200 DEBUG: CAESinkPi:Deinitialize 2021-01-12 14:55:44.820 T:2979001200 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000000 2021-01-12 14:55:44.823 T:2979001200 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0xb0f0d308 2021-01-12 14:55:45.572 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:45.830 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:55:46.072 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:55:46.072 T:2733892464 DEBUG: CecLogMessage - << 17 2021-01-12 14:55:47.072 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:47.360 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:47.572 T:2733892464 DEBUG: CecLogMessage - << 17 2021-01-12 14:55:48.573 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:48.890 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:55:49.073 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:49.073 T:2733892464 DEBUG: CecLogMessage - Tuner 3 (7): device status changed into 'not present' 2021-01-12 14:55:49.073 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:50.073 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:50.422 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=7 response=1 2021-01-12 14:55:50.573 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:55:51.574 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:55:51.953 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=7 response=1 2021-01-12 14:55:52.074 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:55:52.074 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Playback 2 (8): POLL 2021-01-12 14:55:52.074 T:2733892464 DEBUG: CecLogMessage - << 18 2021-01-12 14:55:52.074 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:55:52.074 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:55:53.074 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:53.483 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:53.574 T:2733892464 DEBUG: CecLogMessage - << 18 2021-01-12 14:55:54.575 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:55:55.013 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:55:55.075 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:55:55.075 T:2733892464 DEBUG: CecLogMessage - Playback 2 (8): device status changed into 'not present' 2021-01-12 14:55:55.075 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:55:55.075 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:55:56.075 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:55:56.544 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=8 response=1 2021-01-12 14:55:56.575 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:55:57.576 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:55:58.074 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=8 response=1 2021-01-12 14:55:58.076 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:55:58.076 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Recorder 3 (9): POLL 2021-01-12 14:55:58.076 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:59.076 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:55:59.576 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:55:59.604 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:00.577 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:01.077 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:56:01.077 T:2733892464 DEBUG: CecLogMessage - << 19 2021-01-12 14:56:01.134 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:02.077 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:02.577 T:2733892464 DEBUG: CecLogMessage - << 19 2021-01-12 14:56:02.664 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:03.577 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:04.078 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:56:04.078 T:2733892464 DEBUG: CecLogMessage - Recorder 3 (9): device status changed into 'not present' 2021-01-12 14:56:04.078 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:04.078 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Tuner 4 (A): POLL 2021-01-12 14:56:04.196 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:05.078 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:05.578 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:05.728 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=9 response=1 2021-01-12 14:56:06.578 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:07.078 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:56:07.079 T:2733892464 DEBUG: CecLogMessage - << 1a 2021-01-12 14:56:07.260 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=9 response=1 2021-01-12 14:56:08.079 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:08.579 T:2733892464 DEBUG: CecLogMessage - << 1a 2021-01-12 14:56:08.790 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:09.579 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:10.079 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:56:10.079 T:2733892464 DEBUG: CecLogMessage - Tuner 4 (A): device status changed into 'not present' 2021-01-12 14:56:10.079 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:56:10.079 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Playback 3 (B): POLL 2021-01-12 14:56:10.080 T:2733892464 DEBUG: CecLogMessage - << 1b 2021-01-12 14:56:10.080 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:56:10.322 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:11.080 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:11.580 T:2733892464 DEBUG: CecLogMessage - << 1b 2021-01-12 14:56:11.852 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=a response=1 2021-01-12 14:56:12.580 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:13.080 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:56:13.080 T:2733892464 DEBUG: CecLogMessage - Playback 3 (B): device status changed into 'not present' 2021-01-12 14:56:13.080 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:56:13.080 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:56:13.080 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Reserved 1 (C): POLL 2021-01-12 14:56:13.080 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:56:13.081 T:2733892464 DEBUG: CecLogMessage - << 1c 2021-01-12 14:56:13.081 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:56:13.081 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:13.383 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=a response=1 2021-01-12 14:56:14.080 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:14.581 T:2733892464 DEBUG: CecLogMessage - << 1c 2021-01-12 14:56:14.915 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=b response=1 2021-01-12 14:56:15.581 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:16.081 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:56:16.081 T:2733892464 DEBUG: CecLogMessage - Reserved 1 (C): device status changed into 'not present' 2021-01-12 14:56:16.081 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Reserved 2 (D): POLL 2021-01-12 14:56:16.081 T:2733892464 DEBUG: CecLogMessage - << 1d 2021-01-12 14:56:16.081 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:119.553 2021-01-12 14:56:16.081 T:2733892464 DEBUG: CecLogMessage - Key unknown: idle (duration:3000) (ff) timeout:-1460286344ms (rel:1000,rep:0,prs:0,rel:0) 2021-01-12 14:56:16.446 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=b response=1 2021-01-12 14:56:17.081 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:17.581 T:2733892464 DEBUG: CecLogMessage - << 1d 2021-01-12 14:56:17.978 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=c response=1 2021-01-12 14:56:18.582 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:19.082 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:56:19.082 T:2733892464 DEBUG: CecLogMessage - Reserved 2 (D): device status changed into 'not present' 2021-01-12 14:56:19.082 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:56:19.082 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Free use (E): POLL 2021-01-12 14:56:19.082 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:19.508 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=c response=1 2021-01-12 14:56:20.082 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:20.582 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:21.041 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=d response=1 2021-01-12 14:56:21.583 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:22.083 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:56:22.083 T:2733892464 DEBUG: CecLogMessage - << 1e 2021-01-12 14:56:22.171 T:3002069872 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms 2021-01-12 14:56:22.171 T:2868253552 DEBUG: Thread Timer start, auto delete: false 2021-01-12 14:56:22.195 T:3011753296 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:22.195 T:3011753296 DEBUG: HandleKey: down (0xf081) pressed, action is Down 2021-01-12 14:56:22.195 T:2979001200 INFO: CActiveAESink::OpenSink - initialize sink 2021-01-12 14:56:22.195 T:2979001200 DEBUG: CActiveAESink::OpenSink - trying to open device PI:HDMI 2021-01-12 14:56:22.196 T:2979001200 DEBUG: CAESinkPi:Initialize Format:15 Channels:2 Samplerate:44100 framesize:8 bufsize:17640 bytes/s=352800.00 dest=PI:HDMI 2021-01-12 14:56:22.196 T:2979001200 DEBUG: CAESinkPi:SetAudioProps hdmi_stream_channels 0 hdmi_channel_map 00000008 2021-01-12 14:56:22.198 T:2979001200 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.audio_render input port 100 output port 100 m_handle 0xb0f0d308 2021-01-12 14:56:22.200 T:2979001200 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.audio_render) - port(100), nBufferCountMin(1), nBufferCountActual(2), nBufferSize(17648), nBufferAlignment(16) 2021-01-12 14:56:22.201 T:2979001200 DEBUG: CActiveAESink::OpenSink - SinkPi Initialized: 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Output Device : HDMI 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Sample Rate : 44100 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Sample Format : AE_FMT_FLOAT 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Channel Count : 2 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Channel Layout: FL, FR 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Frames : 2205 2021-01-12 14:56:22.201 T:2979001200 DEBUG: Frame Size : 8 2021-01-12 14:56:22.211 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=d response=1 2021-01-12 14:56:22.379 T:2868253552 DEBUG: Thread Timer 2868253552 terminating 2021-01-12 14:56:22.395 T:3011753296 DEBUG: Keyboard: scancode: 0x6c, sym: 0x0112, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:22.451 T:3002069872 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms 2021-01-12 14:56:22.451 T:2868253552 DEBUG: Thread Timer start, auto delete: false 2021-01-12 14:56:22.462 T:3011753296 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:22.462 T:3011753296 DEBUG: HandleKey: up (0xf080) pressed, action is Up 2021-01-12 14:56:22.571 T:2868253552 DEBUG: Thread Timer 2868253552 terminating 2021-01-12 14:56:22.595 T:3011753296 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:22.619 T:3002069872 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms 2021-01-12 14:56:22.619 T:2868253552 DEBUG: Thread Timer start, auto delete: false 2021-01-12 14:56:22.628 T:3011753296 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:22.628 T:3011753296 DEBUG: HandleKey: up (0xf080) pressed, action is Up 2021-01-12 14:56:22.739 T:2868253552 DEBUG: Thread Timer 2868253552 terminating 2021-01-12 14:56:22.761 T:3011753296 DEBUG: Keyboard: scancode: 0x67, sym: 0x0111, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:23.083 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:23.179 T:3002069872 DEBUG: CLibInputKeyboard::ProcessKey - using delay: 500ms repeat: 33ms 2021-01-12 14:56:23.179 T:2868253552 DEBUG: Thread Timer start, auto delete: false 2021-01-12 14:56:23.185 T:3011753296 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:23.185 T:3011753296 DEBUG: HandleKey: right (0xf083) pressed, action is Right 2021-01-12 14:56:23.347 T:2868253552 DEBUG: Thread Timer 2868253552 terminating 2021-01-12 14:56:23.352 T:3011753296 DEBUG: Keyboard: scancode: 0x6a, sym: 0x0113, unicode: 0x0000, modifier: 0x0 2021-01-12 14:56:23.583 T:2733892464 DEBUG: CecLogMessage - << 1e 2021-01-12 14:56:23.743 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:24.584 T:2733892464 DEBUG: CecLogMessage - command 'feature abort' timeout 2021-01-12 14:56:25.084 T:2675241840 DEBUG: UpdateAudioSystemStatus - no CEC capable amplifier found 2021-01-12 14:56:25.084 T:2733892464 DEBUG: CecLogMessage - >> POLL not sent 2021-01-12 14:56:25.084 T:2733892464 DEBUG: CecLogMessage - Free use (E): device status changed into 'not present' 2021-01-12 14:56:25.084 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:25.087 T:3011753296 DEBUG: ------ Window Init (DialogNotification.xml) ------ 2021-01-12 14:56:25.087 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:56:25.273 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:26.086 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:26.586 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:26.804 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=e response=1 2021-01-12 14:56:27.586 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:28.086 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:56:28.087 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:131.559 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:56:28.087 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:56:28.087 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:56:28.088 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:56:28.088 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:28.088 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:56:28.088 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:56:28.095 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:56:28.334 T:2733892464 DEBUG: Previous line repeats 1 times. 2021-01-12 14:56:28.334 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=0 initiator=1 destination=e response=1 2021-01-12 14:56:29.087 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:29.587 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:29.868 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:30.587 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:30.835 T:3011753296 DEBUG: ------ Window Deinit (DialogNotification.xml) ------ 2021-01-12 14:56:31.088 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:56:31.088 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:31.399 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:32.088 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:32.588 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:32.929 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:33.588 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:34.089 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:56:34.089 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:34.463 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:35.089 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:35.589 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:35.993 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:36.589 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:37.089 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:56:37.090 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:56:37.090 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:56:37.090 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:37.090 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:56:37.523 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:38.090 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:38.590 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:39.055 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:39.590 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:40.091 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:56:40.091 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:40.586 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:41.091 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:41.591 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:42.120 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:42.591 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:43.092 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:56:43.092 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:43.651 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:44.092 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:44.592 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:45.181 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:45.592 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:56:46.093 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:149.565 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:56:46.093 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:56:46.093 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:149.565 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:56:46.094 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:56:46.094 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:56:46.101 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:56:46.712 T:2733892464 DEBUG: Previous line repeats 3 times. 2021-01-12 14:56:46.712 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:47.093 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:47.593 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:48.243 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:48.594 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:49.094 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:56:49.094 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:49.773 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:50.094 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:50.594 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:51.304 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:51.595 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:52.095 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:56:52.095 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:52.835 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:56:53.095 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:53.595 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:56:54.365 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:54.596 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:56:55.096 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:56:55.096 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:56:55.096 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:56:55.096 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:55.096 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:56:55.896 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:56:56.096 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:56.596 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:56:57.427 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:57.596 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:56:58.097 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:56:58.097 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:56:58.958 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:56:59.097 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:56:59.597 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:57:00.492 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:57:00.597 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:57:01.098 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:57:01.098 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:02.025 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:57:02.098 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:02.598 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:03.557 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:57:03.598 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:04.098 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:04.099 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:167.571 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:04.099 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:04.099 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:04.100 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:167.571 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:04.100 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:04.100 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:167.571 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:04.100 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:04.101 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:04.101 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:167.571 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:04.101 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:04.101 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:04.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:04.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:04.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:04.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:04.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:04.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:57:04.104 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:04.107 T:2868253552 DEBUG: Thread JobWorker start, auto delete: true 2021-01-12 14:57:04.123 T:2868253552 DEBUG: Previous line repeats 1 times. 2021-01-12 14:57:04.124 T:2868253552 DEBUG: ffmpeg[AAF61370]: [swscaler] No accelerated colorspace conversion found from yuv420p to bgra. 2021-01-12 14:57:04.162 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:05.088 T:2733892464 DEBUG: Previous line repeats 6 times. 2021-01-12 14:57:05.089 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:57:05.099 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:57:05.600 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:57:06.600 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:57:06.622 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:57:07.100 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:57:07.100 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:57:08.100 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:57:08.153 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:57:08.601 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:57:09.601 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:57:09.683 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:57:10.101 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:57:10.101 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:11.101 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:11.215 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:57:11.601 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:12.602 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:12.745 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:57:13.102 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:57:13.102 T:2733892464 DEBUG: CecLogMessage - >> 0f:87:00:00:f0 2021-01-12 14:57:13.102 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> Broadcast (F): vendor id Pulse Eight (1582) 2021-01-12 14:57:13.102 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:57:13.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 2021-01-12 14:57:14.104 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:57:14.276 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:57:14.604 T:2733892464 DEBUG: CecLogMessage - << 1f:87:00:15:82 2021-01-12 14:57:15.604 T:2733892464 DEBUG: CecLogMessage - command 'device vendor id' timeout 2021-01-12 14:57:15.806 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:57:16.105 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:57:16.105 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:57:17.105 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:57:17.337 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:57:17.605 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:57:18.606 T:2733892464 DEBUG: CecLogMessage - command 'image view on' timeout 2021-01-12 14:57:18.869 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:57:19.106 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:57:19.106 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:20.106 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:20.400 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=87 initiator=1 destination=f response=0 2021-01-12 14:57:20.606 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:21.607 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:21.930 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - failed to make 'Recorder 1' the active source. will retry later 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.107 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.579 2021-01-12 14:57:22.107 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:1ms 2021-01-12 14:57:22.108 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 1 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.108 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:22.108 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.108 T:2733892464 DEBUG: CecLogMessage - >> 01:44:04 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:4 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0) 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - key pressed: right (4, 0) 2021-01-12 14:57:22.109 T:2733892464 DEBUG: PushCecKeypress - received key a8 duration 0 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:04 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - key released: right (4) D:0ms 2021-01-12 14:57:22.109 T:2733892464 DEBUG: PushCecKeypress - received key a8 duration 0 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.109 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.109 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:22.110 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.110 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:22.110 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.110 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.111 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:22.111 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.111 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.112 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(2) duration(0) 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.112 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.580 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:22.112 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.112 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:57:22.113 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.581 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:0ms 2021-01-12 14:57:22.113 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:22.113 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:22.113 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:185.581 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:0ms 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.114 T:2733892464 DEBUG: Previous line repeats 1 times. 2021-01-12 14:57:22.115 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.115 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.115 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:22.115 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:22.128 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:22.228 T:3011753296 DEBUG: Previous line repeats 2 times. 2021-01-12 14:57:22.228 T:3011753296 DEBUG: HandleKey: 168 (0xa8, obc87) pressed, action is Right 2021-01-12 14:57:22.295 T:3011753296 DEBUG: Previous line repeats 1 times. 2021-01-12 14:57:22.295 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:22.595 T:3011753296 DEBUG: Previous line repeats 8 times. 2021-01-12 14:57:22.595 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:57:22.662 T:3011753296 DEBUG: Previous line repeats 1 times. 2021-01-12 14:57:22.662 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:23.109 T:2733892464 DEBUG: CecLogMessage - << powering on 'TV' (0) 2021-01-12 14:57:23.109 T:2733892464 DEBUG: CecLogMessage - << 10:04 2021-01-12 14:57:23.461 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> broadcast (F): active source (1000) 2021-01-12 14:57:23.461 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:24.461 T:2733892464 DEBUG: CecLogMessage - command 'active source' timeout 2021-01-12 14:57:24.961 T:2733892464 DEBUG: CecLogMessage - << 1f:82:10:00 2021-01-12 14:57:24.991 T:2733892464 DEBUG: CecLogMessage - << Recorder 1 (1) -> TV (0): menu state 'activated' 2021-01-12 14:57:24.991 T:2733892464 DEBUG: CecLogMessage - << 10:8e:00 2021-01-12 14:57:25.991 T:2733892464 DEBUG: CecLogMessage - command 'menu status' timeout 2021-01-12 14:57:26.491 T:2733892464 DEBUG: CecLogMessage - << 10:8e:00 2021-01-12 14:57:26.521 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:57:27.492 T:2733892464 DEBUG: CecLogMessage - command 'menu status' timeout 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - >> 01:44:03 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton left (3) D:0ms cur:3 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - key pressed: left (3) current(ff) duration(0) 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - Changed key left (3) D:0ms cur:ff 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - key pressed: left (3, 0) 2021-01-12 14:57:27.992 T:2733892464 DEBUG: PushCecKeypress - received key a9 duration 0 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:191.464 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - Key left: idle (duration:0) (3) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:27.992 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:03 2021-01-12 14:57:27.993 T:2733892464 DEBUG: CecLogMessage - key released: left (3) D:0ms 2021-01-12 14:57:27.993 T:2733892464 DEBUG: PushCecKeypress - received key a9 duration 0 2021-01-12 14:57:27.993 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:27.993 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:27.999 T:3011753296 DEBUG: HandleKey: 169 (0xa9, obc86) pressed, action is Left 2021-01-12 14:57:28.051 T:2733892464 DEBUG: Previous line repeats 1 times. 2021-01-12 14:57:28.052 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=4 initiator=1 destination=0 response=0 2021-01-12 14:57:29.586 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=82 initiator=1 destination=f response=0 2021-01-12 14:57:31.117 T:2733892464 WARNING: Previous line repeats 1 times. 2021-01-12 14:57:31.117 T:2733892464 DEBUG: CecLogMessage - >> 01:44:04 2021-01-12 14:57:31.117 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:4 2021-01-12 14:57:31.117 T:2733892464 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0) 2021-01-12 14:57:31.117 T:2733892464 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff 2021-01-12 14:57:31.117 T:2733892464 DEBUG: CecLogMessage - key pressed: right (4, 0) 2021-01-12 14:57:31.118 T:2733892464 DEBUG: PushCecKeypress - received key a8 duration 0 2021-01-12 14:57:31.118 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:194.590 2021-01-12 14:57:31.118 T:2733892464 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:31.118 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:31.120 T:3011753296 DEBUG: HandleKey: 168 (0xa8, obc87) pressed, action is Right 2021-01-12 14:57:31.617 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:195.090 2021-01-12 14:57:31.617 T:2733892464 DEBUG: CecLogMessage - Key unknown: idle (duration:500) (ff) timeout:-1226607324ms (rel:1000,rep:0,prs:0,rel:0) 2021-01-12 14:57:32.648 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8e initiator=1 destination=0 response=0 2021-01-12 14:57:32.648 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:04 2021-01-12 14:57:32.648 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:34.123 T:2876646256 DEBUG: Thread JobWorker 2876646256 terminating (autodelete) 2021-01-12 14:57:34.129 T:2868253552 DEBUG: Thread JobWorker 2868253552 terminating (autodelete) 2021-01-12 14:57:34.179 T:2733892464 WARNING: CecLogMessage - unhandled response received: opcode=8e initiator=1 destination=0 response=0 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - >> 01:44:03 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton left (3) D:0ms cur:3 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - key pressed: left (3) current(ff) duration(0) 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - Changed key left (3) D:0ms cur:ff 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - key pressed: left (3, 0) 2021-01-12 14:57:34.179 T:2733892464 DEBUG: PushCecKeypress - received key a9 duration 0 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:197.651 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - Key left: idle (duration:0) (3) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:03 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - key released: left (3) D:0ms 2021-01-12 14:57:34.179 T:2733892464 DEBUG: PushCecKeypress - received key a9 duration 0 2021-01-12 14:57:34.179 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:34.189 T:3011753296 DEBUG: HandleKey: 169 (0xa9, obc86) pressed, action is Left 2021-01-12 14:57:35.298 T:2733892464 DEBUG: Previous line repeats 1 times. 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:35.298 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:198.770 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:35.298 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:35.328 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:35.424 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:35.425 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:127ms 2021-01-12 14:57:35.425 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 127 2021-01-12 14:57:35.425 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:35.917 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:199.389 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:35.917 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:35.928 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:36.051 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:36.051 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:135ms 2021-01-12 14:57:36.051 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 135 2021-01-12 14:57:36.052 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:36.320 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:36.320 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:36.321 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:36.321 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:36.321 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:36.321 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:36.321 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:199.793 2021-01-12 14:57:36.321 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:36.321 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:36.328 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:36.454 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:36.455 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:134ms 2021-01-12 14:57:36.455 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 134 2021-01-12 14:57:36.455 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:57:36.888 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:200.360 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:36.888 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:36.895 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:57:37.034 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:57:37.034 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:146ms 2021-01-12 14:57:37.034 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 146 2021-01-12 14:57:37.034 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:57:37.509 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:200.981 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:37.509 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:37.528 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:57:37.604 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:57:37.604 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:95ms 2021-01-12 14:57:37.604 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 95 2021-01-12 14:57:37.604 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:38.231 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:201.703 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:38.231 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:38.262 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:38.357 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:38.357 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:126ms 2021-01-12 14:57:38.357 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 126 2021-01-12 14:57:38.357 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:38.551 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:202.023 2021-01-12 14:57:38.551 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:38.552 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:38.562 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:38.672 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:38.673 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:122ms 2021-01-12 14:57:38.673 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 122 2021-01-12 14:57:38.673 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:38.767 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:38.767 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:38.767 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:38.767 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:38.768 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:38.768 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:38.768 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:202.240 2021-01-12 14:57:38.768 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:38.768 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:38.795 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:38.864 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:38.864 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:96ms 2021-01-12 14:57:38.864 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 96 2021-01-12 14:57:38.864 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:39.260 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:57:39.260 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:57:39.260 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:57:39.260 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:57:39.260 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:57:39.260 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:57:39.260 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:202.732 2021-01-12 14:57:39.261 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:39.261 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:39.262 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:57:39.394 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:57:39.394 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:135ms 2021-01-12 14:57:39.394 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 135 2021-01-12 14:57:39.394 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:57:39.800 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:203.272 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:39.800 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:39.828 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:57:39.953 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:57:39.953 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:154ms 2021-01-12 14:57:39.953 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 154 2021-01-12 14:57:39.954 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:57:40.100 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:57:40.100 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:57:40.100 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:57:40.101 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:57:40.101 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:57:40.101 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:57:40.101 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:203.573 2021-01-12 14:57:40.101 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:57:40.101 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:57:40.128 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:57:40.195 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:57:40.195 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:94ms 2021-01-12 14:57:40.195 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 94 2021-01-12 14:57:40.195 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - >> 01:44:02 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:2 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - key pressed: down (2, 0) 2021-01-12 14:58:09.129 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 0 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:232.601 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:09.129 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:09.162 T:3011753296 DEBUG: HandleKey: 167 (0xa7, obc88) pressed, action is Down 2021-01-12 14:58:09.252 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:02 2021-01-12 14:58:09.252 T:2733892464 DEBUG: CecLogMessage - key released: down (2) D:123ms 2021-01-12 14:58:09.252 T:2733892464 DEBUG: PushCecKeypress - received key a7 duration 123 2021-01-12 14:58:09.252 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:09.710 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:09.710 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:09.710 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:09.711 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:09.711 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:09.711 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:09.711 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:233.183 2021-01-12 14:58:09.711 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:09.711 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:09.728 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:09.813 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:09.813 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:102ms 2021-01-12 14:58:09.813 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 102 2021-01-12 14:58:09.813 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:10.099 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:233.571 2021-01-12 14:58:10.099 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:10.100 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:10.128 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:10.234 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:10.234 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:135ms 2021-01-12 14:58:10.234 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 135 2021-01-12 14:58:10.234 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:10.558 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:10.559 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:234.031 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:10.559 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:10.562 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:10.681 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:10.681 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:122ms 2021-01-12 14:58:10.681 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 122 2021-01-12 14:58:10.681 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:10.974 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:234.446 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:10.974 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:10.995 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:11.102 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:11.102 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:128ms 2021-01-12 14:58:11.102 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 128 2021-01-12 14:58:11.102 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:11.418 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:234.890 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:11.418 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:11.428 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:11.505 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:11.505 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:87ms 2021-01-12 14:58:11.505 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 87 2021-01-12 14:58:11.505 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:12.110 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:235.582 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:12.110 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:12.128 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:12.213 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:12.213 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:103ms 2021-01-12 14:58:12.213 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 103 2021-01-12 14:58:12.213 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B) 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - >> 01:44:01 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:1 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - key pressed: up (1, 0) 2021-01-12 14:58:12.798 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 0 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - CheckKeypressTimeout T:236.270 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:-1460286344ms (rel:500,rep:0,prs:500,rel:0) 2021-01-12 14:58:12.798 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): user control pressed (44) 2021-01-12 14:58:12.828 T:3011753296 DEBUG: HandleKey: 166 (0xa6, obc89) pressed, action is Up 2021-01-12 14:58:12.928 T:2733892464 DEBUG: CecLogMessage - >> 01:8b:01 2021-01-12 14:58:12.928 T:2733892464 DEBUG: CecLogMessage - key released: up (1) D:131ms 2021-01-12 14:58:12.929 T:2733892464 DEBUG: PushCecKeypress - received key a6 duration 131 2021-01-12 14:58:12.929 T:2733892464 DEBUG: CecLogMessage - >> TV (0) -> Recorder 1 (1): vendor remote button up (8B)