2021-11-23 09:27:22.471 T:1191    DEBUG <general>: ------ Window Init (Home.xml) ------
2021-11-23 09:27:30.113 T:7687    DEBUG <general>: Thread JobWorker 3329213056 terminating (autodelete)
2021-11-23 09:27:30.113 T:7686    DEBUG <general>: Thread JobWorker 3395285632 terminating (autodelete)
2021-11-23 09:27:44.163 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0
2021-11-23 09:27:44.163 T:1234    DEBUG <general>: CecLogMessage - >> 0f:a0:00:80:45:20:01:10
2021-11-23 09:27:44.163 T:1234    DEBUG <general>: CecLogMessage - TV (0): power status changed from 'on' to 'standby'
2021-11-23 09:27:44.163 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0)
2021-11-23 09:27:44.233 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36
2021-11-23 09:27:44.233 T:1234    DEBUG <general>: CecLogMessage - >> 0f:36
2021-11-23 09:27:44.234 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Broadcast (F): standby (36)
2021-11-23 09:27:44.863 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36
2021-11-23 09:27:44.863 T:1234    DEBUG <general>: CecLogMessage - >> 0f:36
2021-11-23 09:28:00.604 T:7747    DEBUG <general>: Thread JobWorker start, auto delete: true
2021-11-23 09:28:07.818 T:1234     INFO <general>: Skipped 1 duplicate messages..
2021-11-23 09:28:07.818 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff
2021-11-23 09:28:09.831 T:1234    DEBUG <general>: CecLogMessage - changing physical address to FFFF
2021-11-23 09:28:09.831 T:1234    DEBUG <general>: CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff
2021-11-23 09:28:09.831 T:1234    DEBUG <general>: SetConfigurationFromLibCEC - settings updated by libCEC
2021-11-23 09:28:10.199 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2000
2021-11-23 09:28:10.333 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2000
2021-11-23 09:28:10.333 T:1234    DEBUG <general>: CecLogMessage - changing physical address to 2000
2021-11-23 09:28:10.333 T:1234    DEBUG <general>: CecLogMessage - marking Playback 1 (4) as inactive source
2021-11-23 09:28:10.333 T:1234    DEBUG <general>: CecLogMessage - >> source deactivated: Playback 1 (4)
2021-11-23 09:28:10.333 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 2000
2021-11-23 09:28:10.333 T:1234    DEBUG <general>: CecLogMessage - << 4f:84:20:00:04
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - making Playback 1 (4) the active source
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - TV (0): power status changed from 'standby' to 'in transition from standby to on'
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - >> source activated: Playback 1 (4)
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - sending active source message for 'Playback 1'
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - << powering on 'TV' (0)
2021-11-23 09:28:10.746 T:1234    DEBUG <general>: CecLogMessage - << 40:04
2021-11-23 09:28:10.813 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04
2021-11-23 09:28:10.813 T:1234    DEBUG <general>: CecLogMessage - << requesting power status of 'Playback 1' (0)
2021-11-23 09:28:10.813 T:1234    DEBUG <general>: CecLogMessage - << 40:8f
2021-11-23 09:28:10.879 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=8f
2021-11-23 09:28:10.880 T:1234    DEBUG <general>: CecLogMessage - source switch is currently not allowed by command handler
2021-11-23 09:28:10.880 T:1234    DEBUG <general>: CecLogMessage - failed to make 'Playback 1' the active source. will retry later
2021-11-23 09:28:10.880 T:1234    DEBUG <general>: SetConfigurationFromLibCEC - settings updated by libCEC
2021-11-23 09:28:11.093 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0
2021-11-23 09:28:11.093 T:1234    DEBUG <general>: CecLogMessage - >> 0f:a0:00:80:45:20:00:00
2021-11-23 09:28:11.093 T:1234    DEBUG <general>: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on'
2021-11-23 09:28:11.093 T:1234    DEBUG <general>: CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32
2021-11-23 09:28:11.093 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0)
2021-11-23 09:28:11.444 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89
2021-11-23 09:28:11.445 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2000)
2021-11-23 09:28:11.445 T:1234    DEBUG <general>: CecLogMessage - << 4f:82:20:00
2021-11-23 09:28:11.559 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82
2021-11-23 09:28:11.653 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=04 opcode=90
2021-11-23 09:28:11.653 T:1234    DEBUG <general>: CecLogMessage - >> 04:90:02
2021-11-23 09:28:11.653 T:1234    DEBUG <general>: CecLogMessage - TV (0): power status changed from 'on' to 'in transition from standby to on'
2021-11-23 09:28:11.653 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Playback 1 (4): report power status (90)
2021-11-23 09:28:12.653 T:1234    DEBUG <general>: CecLogMessage - << powering on 'TV' (0)
2021-11-23 09:28:12.654 T:1234    DEBUG <general>: CecLogMessage - << 40:04
2021-11-23 09:28:12.706 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04
2021-11-23 09:28:12.706 T:1234    DEBUG <general>: CecLogMessage - source switch is currently not allowed by command handler
2021-11-23 09:28:12.706 T:1234    DEBUG <general>: CecLogMessage - failed to make 'Playback 1' the active source. will retry later
2021-11-23 09:28:13.707 T:1234    DEBUG <general>: CecLogMessage - << powering on 'TV' (0)
2021-11-23 09:28:13.707 T:1234    DEBUG <general>: CecLogMessage - << 40:04
2021-11-23 09:28:13.759 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04
2021-11-23 09:28:13.759 T:1234    DEBUG <general>: CecLogMessage - source switch is currently not allowed by command handler
2021-11-23 09:28:13.760 T:1234    DEBUG <general>: CecLogMessage - failed to make 'Playback 1' the active source. will retry later
2021-11-23 09:28:14.760 T:1234    DEBUG <general>: CecLogMessage - << powering on 'TV' (0)
2021-11-23 09:28:14.760 T:1234    DEBUG <general>: CecLogMessage - << 40:04
2021-11-23 09:28:14.812 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04
2021-11-23 09:28:14.813 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2000)
2021-11-23 09:28:14.813 T:1234    DEBUG <general>: CecLogMessage - << 4f:82:20:00
2021-11-23 09:28:14.928 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82
2021-11-23 09:28:14.928 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> TV (0): menu state 'activated'
2021-11-23 09:28:14.928 T:1234    DEBUG <general>: CecLogMessage - << 40:8e:00
2021-11-23 09:28:15.019 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=3 addr=40 opcode=8e
2021-11-23 09:28:18.855 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff
2021-11-23 09:28:20.157 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=2000
2021-11-23 09:28:20.291 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=2000
2021-11-23 09:28:20.754 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0
2021-11-23 09:28:20.754 T:1234    DEBUG <general>: CecLogMessage - >> 04:a0:00:80:45:06:05
2021-11-23 09:28:20.754 T:1234    DEBUG <general>: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on'
2021-11-23 09:28:20.754 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2000)
2021-11-23 09:28:20.754 T:1234    DEBUG <general>: CecLogMessage - << 4f:82:20:00
2021-11-23 09:28:20.755 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0)
2021-11-23 09:28:20.865 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82
2021-11-23 09:28:21.945 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0
2021-11-23 09:28:21.945 T:1234    DEBUG <general>: CecLogMessage - >> 04:a0:00:80:45:06:05
2021-11-23 09:28:21.945 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2000)
2021-11-23 09:28:21.945 T:1234    DEBUG <general>: CecLogMessage - << 4f:82:20:00
2021-11-23 09:28:21.945 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0)
2021-11-23 09:28:22.056 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82
2021-11-23 09:28:23.130 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=7 addr=04 opcode=a0
2021-11-23 09:28:23.130 T:1234    DEBUG <general>: CecLogMessage - >> 04:a0:00:80:45:06:05
2021-11-23 09:28:23.130 T:1234    DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (2000)
2021-11-23 09:28:23.130 T:1234    DEBUG <general>: CecLogMessage - << 4f:82:20:00
2021-11-23 09:28:23.131 T:1234    DEBUG <general>: CecLogMessage - >> TV (0) -> Playback 1 (4): vendor command with id (A0)
2021-11-23 09:28:23.241 T:1234    DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82
2021-11-23 09:28:30.617 T:7747    DEBUG <general>: Thread JobWorker 3295642240 terminating (autodelete)
2021-11-23 09:28:30.617 T:7748    DEBUG <general>: Thread JobWorker 3329213056 terminating (autodelete)
2021-11-23 09:29:00.657 T:7831    DEBUG <general>: Thread JobWorker start, auto delete: true