TV turn on, without interaction

taszilo19 · 17 September 2021 12:00

Hi,

before the last august update I had no issue, but since I have permanent problem. I’ve seen that this problem were coming up many times, but now I’m effected too. When I finish my watch and turn off my TV then after it happens that the TV turns on without my influence. The timeframe is very different, when it turns on. WHat I have identified that I should not let the menu somewhere deep, for example in a library deeply in a file system folder, but should go up to the main menu. sometimes it helps, but many times doesn’t. Is there any solution? I disabled all the CEC, but does not realy help. It is realy annoying that in the middle of the night I realize that my TV is on and if I turn it of, does not mean that it won’t turn on again.

Thanks and regards

fzinken · 17 September 2021 12:26

Provide Debug enabled logs they may show a reason

taszilo19 · 18 September 2021 20:17

Hi,

I’ve uploaded the following log:
https://paste.osmc.tv/fuxofujobe

What I’ve seen when the TV was turn on again withou my any action:
2021-09-18 22:12:04.002 T:801 DEBUG : SetConfigurationFromLibCEC - settings updated by libCEC
2021-09-18 22:12:12.432 T:1076 DEBUG : Thread JobWorker 1624330432 terminating (autodelete)
2021-09-18 22:12:12.461 T:1064 DEBUG : Thread JobWorker 1801961664 terminating (autodelete)
2021-09-18 22:12:12.939 T:801 DEBUG : SetConfigurationFromLibCEC - settings updated by libCEC
2021-09-18 22:12:44.928 T:801 DEBUG : PushCecKeypress - received key d8 duration 0
2021-09-18 22:12:44.937 T:600 DEBUG : HandleKey: menu (0xd8) pressed, action is Back

But please analyize the log. What can I do to avoid this automatic turn on?

thanks!

fzinken · 19 September 2021 03:34

How did you do that?

taszilo19 · 22 September 2021 08:16

Hi, I did it from the settings menu/input, the only thing is the main CEC what I have left enabled. Else I would not be able top control. And I did it only after the problem occured.

fzinken · 22 September 2021 08:47

What do you mean with main CEC?
Did you disable CEC under Settings - System - Input?

taszilo19 · 22 September 2021 20:30

Hi,
Yes. If I disable CEC there and control via phone then no error. Tv remains switched off. The log what I sent was with CEC enabled but only the first menu CEC enabled (in the config you have wrote too), and then the problem is there, TV turn on.

Is there anything I can do? Do you see anything in the log? My family don’t like controlling the tv with phone but none of us like to wake up at night many times to switch off the tv again amd agin.

Thank

fzinken · 23 September 2021 02:55

Well as you wrote above what you see in the logs is that a key is pressed at the time of TV wake up.

So I really wonder if that was the time the TV woke up as you were playing a movie at that time?

But also from the logs it seems your power supply is not up to the job. Maybe a stable power supply to the Pi may also solve the problem

Sep 18 22:12:49 osmc kernel: Under-voltage detected! (0x00050005)
Sep 18 22:12:53 osmc kernel: Voltage normalised (0x00000000)

taszilo19 · 23 September 2021 06:54

Hi,

Ok, maybe my inserted log was missleading. I try to correct but please see the whole log.
So what I wanted to show (enhancing littlebit with more context):
This is in the log the last active entry (whether I switched off the TV or not I can’t judge, how I can figure out when exactly I pressed TV power off button? is there any log about it?)
2021-09-18 22:11:42.658 T:600 DEBUG : CDRMUtils::DrmFbGetFromBo - using modifier: 0x700000000000001

This is the line and EXACT time, when the TV turns itself!
2021-09-18 22:12:04.002 T:801 DEBUG : SetConfigurationFromLibCEC - settings updated by libCEC

The button press and the other lines in the log, mostly about navigating to log stop and uploading.

The power supply under voltage is not a new issue. Same frequency was before and caused no problem. So deffinitly the problem was comming with the august update. If I turn off CEC then no TV automatic turn on, if only the first menu item is enabled: CEC enabled, then the problem appears.

thanks

fzinken · 23 September 2021 07:47

Maybe try to upload logs via SSH to avoid CEC activity.

Maybe try to enable CEC component specific logging

taszilo19 · 25 September 2021 21:21

Hi,

I’ve created a new log with cec enabled, but I have run into the following problem:

osmc@osmc:~$ grab-logs -A
Logs successfully uploaded.
Logs available at https://paste.osmc.tv/

So no real url like I had list time. I rebooted many times, I tried this too:
osmc@osmc:~$ paste-log .kodi/temp/kodi.log
https://paste.osmc.tv/
https://paste.osmc.tv/
https://paste.osmc.tv/
https://paste.osmc.tv/
https://paste.osmc.tv/
https://paste.osmc.tv/
https://paste.osmc.tv/

But as you can see no real luck. So how I can upload the new log? I tried manually upload on https://paste.osmc.tv, but Pressing the save: Something went wrong error message was comming. Any idea?

Thanks

fzinken · 26 September 2021 02:14

This sounded odd. Maybe a temporary problem with the upload server?
Can you try again and also check what does `ls -lah .kodi/temp/ gives you.

sam_nazarko · 26 September 2021 02:54

Can mean the logs are too big.

fzinken · 26 September 2021 03:13

paste-log would inform if the logs are too big, wouldn’t it?

taszilo19 · 26 September 2021 07:26

Hi Sam, fzinken,

It must be a temporary error as today I was able to upload with paste-log and the URL is generated as expected:
https://paste.osmc.tv/fiwelexela

Now it is too far to give the exact time when the TV was waken up, somewhen around 23:16. If it does not help today I try to reproduce. Hope you will see something in the log.

thanks

taszilo19 · 26 September 2021 07:27

By the way, if you check the previous message you can see that paste-log was giving back wrong answer, no too big size or anything else.

regads

fzinken · 26 September 2021 07:29

Yes, therefore I told @sam_nazarko it must have been a temporary issue and not size too big

fzinken · 26 September 2021 07:38

You started playback of a movie which woke up the TV, I am still not sure about the actual issue

2021-09-25 23:16:19.438 T:752     DEBUG <general>: PushCecKeypress - received key  b duration 75
2021-09-25 23:16:19.438 T:752     DEBUG <general>: CecLogMessage - >> TV (0) -> Playback 1 (4): user control release (45)
2021-09-25 23:16:19.438 T:830     DEBUG <general>: CDVDAudio::Flush - flush audio stream
2021-09-25 23:16:19.439 T:723     DEBUG <general>: CActiveAE::DiscardStream - audio stream deleted
2021-09-25 23:16:19.439 T:723     DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-09-25 23:16:19.440 T:830      INFO <general>: Skipped 2 duplicate messages..
2021-09-25 23:16:19.440 T:830      INFO <general>: Deleting audio codec
2021-09-25 23:16:19.441 T:830      INFO <general>: Closing stream player 2
2021-09-25 23:16:19.441 T:830      INFO <general>: waiting for video thread to exit
2021-09-25 23:16:19.442 T:724      INFO <general>: CActiveAESink::OpenSink - initialize sink
2021-09-25 23:16:19.464 T:832   WARNING <general>: OutputPicture - timeout waiting for buffer
2021-09-25 23:16:19.464 T:832      INFO <general>: thread end: video_thread
2021-09-25 23:16:19.465 T:832     DEBUG <general>: Thread VideoPlayerVideo 1496314048 terminating
2021-09-25 23:16:19.466 T:830      INFO <general>: deleting video codec
2021-09-25 23:16:19.476 T:835     DEBUG <general>: Thread JobWorker start, auto delete: true
2021-09-25 23:16:19.478 T:830     DEBUG <general>: Thread VideoPlayer 1520320704 terminating
2021-09-25 23:16:19.478 T:586      INFO <general>: VideoPlayer: finished waiting
2021-09-25 23:16:19.478 T:586      INFO <general>: CVideoPlayer::CloseFile()
2021-09-25 23:16:19.478 T:586      INFO <general>: VideoPlayer: waiting for threads to exit
2021-09-25 23:16:19.478 T:586      INFO <general>: VideoPlayer: finished waiting
2021-09-25 23:16:19.482 T:586     DEBUG <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2021-09-25 23:16:19.488 T:835      INFO <general>: Deleting settings information for files /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S03.Droid.Tales/LEGO.Star.Wars.S03E03.Droid.Tales.WEB-DL.x264.Hun-Krissz.mp4
2021-09-25 23:16:19.490 T:836     DEBUG <general>: Thread JobWorker start, auto delete: true
2021-09-25 23:16:19.491 T:836     DEBUG <general>: DoWork - Saving file state for video item /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S03.Droid.Tales/LEGO.Star.Wars.S03E03.Droid.Tales.WEB-DL.x264.Hun-Krissz.mp4
2021-09-25 23:16:19.638 T:724     DEBUG <general>: CActiveAESink::OpenSink - trying to open device ALSA:@
2021-09-25 23:16:19.638 T:724      INFO <general>: CAESinkALSA::Initialize - Attempting to open device "@"
2021-09-25 23:16:19.651 T:724      INFO <general>: CAESinkALSA::Initialize - Opened device "sysdefault"
2021-09-25 23:16:19.654 T:724      INFO <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2021-09-25 23:16:19.655 T:724      INFO <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2021-09-25 23:16:19.655 T:724     DEBUG <general>: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820
2021-09-25 23:16:19.659 T:724     DEBUG <general>: CAESinkALSA::InitializeHW - Got: periodSize 2205, bufferSize 8820
2021-09-25 23:16:19.659 T:724     DEBUG <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2021-09-25 23:16:19.659 T:724     DEBUG <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2021-09-25 23:16:19.659 T:724     DEBUG <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2021-09-25 23:16:19.659 T:724     DEBUG <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2021-09-25 23:16:19.659 T:724     DEBUG <general>: CActiveAESink::OpenSink - ALSA Initialized:
2021-09-25 23:16:19.659 T:724     DEBUG <general>:   Output Device : Default (vc4-hdmi MAI PCM vc4-hdmi-hifi-0)
2021-09-25 23:16:19.660 T:724     DEBUG <general>:   Sample Rate   : 44100
2021-09-25 23:16:19.660 T:724     DEBUG <general>:   Sample Format : AE_FMT_S24NE3
2021-09-25 23:16:19.660 T:724     DEBUG <general>:   Channel Count : 2
2021-09-25 23:16:19.660 T:724     DEBUG <general>:   Channel Layout: FL, FR
2021-09-25 23:16:19.660 T:724     DEBUG <general>:   Frames        : 2205
2021-09-25 23:16:19.660 T:724     DEBUG <general>:   Frame Size    : 6
2021-09-25 23:16:19.665 T:723     DEBUG <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2021-09-25 23:16:19.788 T:835     DEBUG <general>: Skipped 1 duplicate messages..
2021-09-25 23:16:19.788 T:835     DEBUG <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2021-09-25 23:16:19.795 T:586     DEBUG <general>: CGUIWindowManager::PreviousWindow: Deactivate
2021-09-25 23:16:19.796 T:586     DEBUG <general>: ------ Window Deinit (VideoOSD.xml) ------
2021-09-25 23:16:19.796 T:586     DEBUG <general>: ------ Window Deinit (VideoFullScreen.xml) ------
2021-09-25 23:16:19.824 T:586     DEBUG <general>: CGUIWindowManager::PreviousWindow: Activate new
2021-09-25 23:16:19.837 T:586     DEBUG <general>: ------ Window Init (MyVideoNav.xml) ------
2021-09-25 23:16:19.841 T:586     DEBUG <general>: CGUIMediaWindow::GetDirectory (/mnt/data-share/Cartoons_Series/Lego.Star.Wars/S03.Droid.Tales/)
2021-09-25 23:16:19.841 T:586     DEBUG <general>:   ParentPath = [/mnt/data-share/Cartoons_Series/Lego.Star.Wars/S03.Droid.Tales/]
2021-09-25 23:16:19.853 T:837     DEBUG <general>: Thread BackgroundLoader start, auto delete: false
2021-09-25 23:16:19.880 T:586     DEBUG <general>: ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-09-25 23:16:19.906 T:837     DEBUG <general>: Thread BackgroundLoader 1561137344 terminating
2021-09-25 23:16:20.257 T:586     DEBUG <general>: ffmpeg[(nil)X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-09-25 23:16:20.342 T:836     DEBUG <general>: ffmpeg[0x719e7138X]: [image2] Custom AVIOContext makes no sense and will be ignored with AVFMT_NOFILE format.
2021-09-25 23:16:20.368 T:586     DEBUG <general>: ------ Window Deinit (DialogSeekBar.xml) ------
2021-09-25 23:16:20.451 T:586     DEBUG <general>: CVideoLayerBridgeDRMPRIME::Disable - setting connector colorspace to Default
2021-09-25 23:16:20.451 T:586     DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 208
2021-09-25 23:16:20.451 T:586     DEBUG <general>: CDRMUtils::DrmFbGetFromBo - using modifier: 0x700000000000001
2021-09-25 23:16:20.502 T:586     DEBUG <general>: ------ Window Deinit (Custom_1109_TopBarOverlay.xml) ------
2021-09-25 23:16:20.503 T:586     DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 207
2021-09-25 23:16:20.503 T:586     DEBUG <general>: CDRMUtils::DrmFbGetFromBo - using modifier: 0x700000000000001
2021-09-25 23:16:20.540 T:586     DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 206
2021-09-25 23:16:20.540 T:586     DEBUG <general>: CDRMUtils::DrmFbGetFromBo - using modifier: 0x700000000000001
2021-09-25 23:16:20.585 T:586     DEBUG <general>: CDRMUtils::DrmFbDestroyCallback - removing framebuffer: 209
2021-09-25 23:16:20.585 T:586     DEBUG <general>: CDRMUtils::DrmFbGetFromBo - using modifier: 0x700000000000001
2021-09-25 23:16:22.409 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0
2021-09-25 23:16:22.409 T:752     DEBUG <general>: CecLogMessage - >> 0f:a0:00:80:45:20:01:10
2021-09-25 23:16:22.409 T:752     DEBUG <general>: CecLogMessage - TV (0): power status changed from 'on' to 'standby'
2021-09-25 23:16:22.409 T:752     DEBUG <general>: CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0)
2021-09-25 23:16:22.479 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36
2021-09-25 23:16:22.479 T:752     DEBUG <general>: CecLogMessage - >> 0f:36
2021-09-25 23:16:22.479 T:752     DEBUG <general>: CecLogMessage - >> TV (0) -> Broadcast (F): standby (36)
2021-09-25 23:16:23.121 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=2 addr=0f opcode=36
2021-09-25 23:16:23.121 T:752     DEBUG <general>: CecLogMessage - >> 0f:36
2021-09-25 23:16:40.655 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=ffff
2021-09-25 23:16:42.673 T:752     DEBUG <general>: CecLogMessage - changing physical address to FFFF
2021-09-25 23:16:42.673 T:752     DEBUG <general>: CecLogMessage - SetDevicePhysicalAddress - not setting invalid physical address ffff
2021-09-25 23:16:42.673 T:752     DEBUG <general>: SetConfigurationFromLibCEC - settings updated by libCEC
2021-09-25 23:16:50.341 T:835     DEBUG <general>: DoWork - trying to extract thumb from video file /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E04.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:50.372 T:836     DEBUG <general>: Thread JobWorker 1520320704 terminating (autodelete)
2021-09-25 23:16:50.862 T:835     DEBUG <general>: Open - probing detected format [matroska,webm]
2021-09-25 23:16:50.869 T:835     DEBUG <general>: Open - avformat_find_stream_info starting
2021-09-25 23:16:50.927 T:835     DEBUG <general>: Open - av_find_stream_info finished
2021-09-25 23:16:50.928 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0000 phys_addr=1000
2021-09-25 23:16:50.929 T:835     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-09-25 23:16:50.929 T:835     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-09-25 23:16:50.929 T:835     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-09-25 23:16:50.929 T:835     DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-09-25 23:16:50.930 T:835     DEBUG <general>: ScanForExternalSubtitles: END (total time: 1 ms)
2021-09-25 23:16:50.931 T:835      INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-09-25 23:16:50.932 T:835     DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-09-25 23:16:50.932 T:835     DEBUG <general>: ExtractThumb - seeking to pos 93774ms (total: 281323ms) in /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E04.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:50.934 T:835     DEBUG <general>: SeekTime - seek ended up on time 92134
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=1000
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - changing physical address to 1000
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - marking Playback 1 (4) as inactive source
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - >> source deactivated: Playback 1 (4)
2021-09-25 23:16:51.078 T:752     DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 1000
2021-09-25 23:16:51.078 T:752     DEBUG <general>: CecLogMessage - << 4f:84:10:00:04
2021-09-25 23:16:51.085 T:835     DEBUG <general>: cached image 'special://masterprofile/Thumbnails/6/6c8f9ab6.jpg' size 540x303
2021-09-25 23:16:51.134 T:835     DEBUG <general>: ExtractThumb - measured 793 ms to extract thumb from file </mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E04.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv> in 17 packets. 
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - making Playback 1 (4) the active source
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - TV (0): power status changed from 'standby' to 'in transition from standby to on'
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - >> source activated: Playback 1 (4)
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - sending active source message for 'Playback 1'
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - << powering on 'TV' (0)
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - << 40:04
2021-09-25 23:16:51.540 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=04
2021-09-25 23:16:51.541 T:752     DEBUG <general>: CecLogMessage - << requesting power status of 'Playback 1' (0)
2021-09-25 23:16:51.541 T:752     DEBUG <general>: CecLogMessage - << 40:8f
2021-09-25 23:16:51.607 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=2 addr=40 opcode=8f
2021-09-25 23:16:51.608 T:752     DEBUG <general>: CecLogMessage - source switch is currently not allowed by command handler
2021-09-25 23:16:51.608 T:752     DEBUG <general>: CecLogMessage - failed to make 'Playback 1' the active source. will retry later
2021-09-25 23:16:51.608 T:752     DEBUG <general>: SetConfigurationFromLibCEC - settings updated by libCEC
2021-09-25 23:16:51.658 T:841     DEBUG <general>: Thread JobWorker start, auto delete: true
2021-09-25 23:16:51.658 T:841     DEBUG <general>: DoWork - trying to extract thumb from video file /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E02.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:51.670 T:841     DEBUG <general>: Open - probing detected format [matroska,webm]
2021-09-25 23:16:51.678 T:841     DEBUG <general>: Open - avformat_find_stream_info starting
2021-09-25 23:16:51.717 T:841     DEBUG <general>: Open - av_find_stream_info finished
2021-09-25 23:16:51.718 T:841     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-09-25 23:16:51.718 T:841     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-09-25 23:16:51.719 T:841     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-09-25 23:16:51.719 T:841     DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-09-25 23:16:51.720 T:841     DEBUG <general>: ScanForExternalSubtitles: END (total time: 1 ms)
2021-09-25 23:16:51.720 T:841      INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-09-25 23:16:51.721 T:841     DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-09-25 23:16:51.721 T:841     DEBUG <general>: ExtractThumb - seeking to pos 73045ms (total: 219136ms) in /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E02.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:51.724 T:841     DEBUG <general>: SeekTime - seek ended up on time 72739
2021-09-25 23:16:51.821 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=8 addr=0f opcode=a0
2021-09-25 23:16:51.821 T:752     DEBUG <general>: CecLogMessage - >> 0f:a0:00:80:45:20:00:00
2021-09-25 23:16:51.821 T:752     DEBUG <general>: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on'
2021-09-25 23:16:51.821 T:752     DEBUG <general>: CecLogMessage - << 40:89:10:02:ff:ff:00:05:05:45:55:5c:58:32
2021-09-25 23:16:51.821 T:752     DEBUG <general>: CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0)
2021-09-25 23:16:51.911 T:841     DEBUG <general>: cached image 'special://masterprofile/Thumbnails/0/09d69986.jpg' size 540x303
2021-09-25 23:16:51.962 T:841     DEBUG <general>: ExtractThumb - measured 304 ms to extract thumb from file </mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E02.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv> in 17 packets. 
2021-09-25 23:16:52.172 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=14 addr=40 opcode=89
2021-09-25 23:16:52.172 T:752     DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): active source (1000)
2021-09-25 23:16:52.172 T:752     DEBUG <general>: CecLogMessage - << 4f:82:10:00
2021-09-25 23:16:52.287 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=4 addr=4f opcode=82
2021-09-25 23:16:52.381 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - ioctl CEC_RECEIVE - rx_status=01 len=3 addr=04 opcode=90
2021-09-25 23:16:52.381 T:752     DEBUG <general>: CecLogMessage - >> 04:90:02
2021-09-25 23:16:52.381 T:752     DEBUG <general>: CecLogMessage - TV (0): power status changed from 'on' to 'in transition from standby to on'
2021-09-25 23:16:52.381 T:752     DEBUG <general>: CecLogMessage - >> TV (0) -> Playback 1 (4): report power status (90)
2021-09-25 23:16:52.986 T:841     DEBUG <general>: DoWork - trying to extract thumb from video file /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E01.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:52.994 T:841     DEBUG <general>: Open - probing detected format [matroska,webm]
2021-09-25 23:16:53.008 T:841     DEBUG <general>: Open - avformat_find_stream_info starting
2021-09-25 23:16:53.062 T:841     DEBUG <general>: Open - av_find_stream_info finished
2021-09-25 23:16:53.062 T:841     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 0
2021-09-25 23:16:53.063 T:841     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 1
2021-09-25 23:16:53.063 T:841     DEBUG <general>: CDVDDemuxFFmpeg::AddStream ID: 2
2021-09-25 23:16:53.063 T:841     DEBUG <general>: ScanForExternalSubtitles: Searching for subtitles...
2021-09-25 23:16:53.064 T:841     DEBUG <general>: ScanForExternalSubtitles: END (total time: 1 ms)
2021-09-25 23:16:53.064 T:841      INFO <general>: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
2021-09-25 23:16:53.065 T:841     DEBUG <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-h264
2021-09-25 23:16:53.065 T:841     DEBUG <general>: ExtractThumb - seeking to pos 135712ms (total: 407136ms) in /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E01.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:53.068 T:841     DEBUG <general>: SeekTime - seek ended up on time 135302
2021-09-25 23:16:53.241 T:841     DEBUG <general>: cached image 'special://masterprofile/Thumbnails/3/3b7a181e.jpg' size 540x303
2021-09-25 23:16:53.293 T:841     DEBUG <general>: ExtractThumb - measured 307 ms to extract thumb from file </mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E01.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv> in 17 packets. 
2021-09-25 23:16:53.381 T:752     DEBUG <general>: CecLogMessage - << powering on 'TV' (0)

taszilo19 · 26 September 2021 08:02

Hi,

The issue that I do NOT start to play any movie after I switched off the TV! So what you should see in the log, I’m not sure that everything is there, but today evening I try to recreate the log and upload, maybe this playing around the upload added more things which missleades, but the exact reproduction steps which should be somehow visible in the log, do everything with TV remote control, no android, no web client:

start to play a movie, scroll in it, pause, play again so normal usages.
press STOP, but do no navigate away from the folder where the movie files is
press power off on TV remote control
wait sometime, sitting in the chair, do not press anything on TV remote or any other client
The issue: TV turns On

If you do not see it from the log, then I need littlehelp, what to turn on in log or what should I enter here. If you see in the log that the movie play powers the TV, then some kind of automatizm does this, but I have no clue, what.

fzinken · 26 September 2021 08:55

Well mark the timestamps of that steps. Also maybe wait a minute after you stop the video before your switch off the TV.

Also you could connect via SSH to OSMC and run cec-client -m to see what is happening.

Also can you post a screen shot of the OSMC CEC config screen.

BTW, which TV do you have? Is it directly connected or via AVR?

In the logs it seems like that Kodi when extracting the thumbnail for the Video is indicating the active source.

2021-09-25 23:16:50.932 T:835     DEBUG <general>: ExtractThumb - seeking to pos 93774ms (total: 281323ms) in /mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E04.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv
2021-09-25 23:16:50.934 T:835     DEBUG <general>: SeekTime - seek ended up on time 92134
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Process - CEC_DQEVENT - CEC_EVENT_STATE_CHANGE - log_addr_mask=0010 phys_addr=1000
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - changing physical address to 1000
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - marking Playback 1 (4) as inactive source
2021-09-25 23:16:51.062 T:752     DEBUG <general>: CecLogMessage - >> source deactivated: Playback 1 (4)
2021-09-25 23:16:51.078 T:752     DEBUG <general>: CecLogMessage - << Playback 1 (4) -> broadcast (F): physical address 1000
2021-09-25 23:16:51.078 T:752     DEBUG <general>: CecLogMessage - << 4f:84:10:00:04
2021-09-25 23:16:51.085 T:835     DEBUG <general>: cached image 'special://masterprofile/Thumbnails/6/6c8f9ab6.jpg' size 540x303
2021-09-25 23:16:51.134 T:835     DEBUG <general>: ExtractThumb - measured 793 ms to extract thumb from file </mnt/data-share/Cartoons_Series/Lego.Star.Wars/S04.The.Resistance.Rises/LEGO.Star.Wars.S04E04.The.Resistance.Rises.720p.WEBRip.x264.HUN.ENG-H73.mkv> in 17 packets. 
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - CLinuxCECAdapterCommunication::Write - ioctl CEC_TRANSMIT - tx_status=01 len=5 addr=4f opcode=84
2021-09-25 23:16:51.474 T:752     DEBUG <general>: CecLogMessage - making Playback 1 (4) the active source