2019.04-1 update skip to time from CEC remote not working

#1

Hi everyone!

I use OSMC on a Raspberry Pi attached to my Sony Bravia by HDMI and have always used the Sony remote to control it over CEC. This has worked brilliantly for a very long time.

Since the recent update one function is completely missing. I used to be able to skip to a particular time in any video using the number keys on the remote. For example keying “1 2 3 4 OK” would skip to 12 minutes and 34 seconds in the currently playing video. A small overlay in the lower right corner would show the entered time for confirmation until OK was pressed.

Now the number keys do absolutely nothing. No overlay, no skip to time. The LED on the TV flashes, confirming that the TV is receiving the IR signal from the remote, and other functions, such as arrow keys, play, pause, stop and so on are working as usual, confirming that data is being passed on over CEC. I have power-cycled both the Pi and the TV and checked all connections.

Any advice would be appreciated. I am unsure if there’s a new setting I need to tweak or if this is a bug in the new release.

Thanks!

#2

First thing I’d check is if the keys are picked up in debug logs.
Has your Sony TV had an update recently?

Sam

#3

Thank you, Sam, for looking at this issue. There have been no updates to the Sony TV at all for quite some time and I’m fairly certain the issue started at the same time as the most recent OSMC update.

Logs: https://paste.osmc.tv/omugutawob

#4

Search for PushCecKeypress in your logs to see if you can see the buttons being detected.

Sam

#5

Yep, there are a lot of those, since this is how I navigate. For example:

2019-04-25 00:02:50.746 T:1746060000   DEBUG: PushCecKeypress - received key  b duration 0 (rep:0 size:0)
2019-04-25 00:02:50.746 T:1746060000   DEBUG: PushCecKeypress - added key  b
2019-04-25 00:02:50.759 T:1915676208   DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select

Appears to show me pressing the centre “point and shoot” button after using the arrows to navigate to a video. The video started playing correctly.

The following section seems to show it receiving the number keys as I attempt to skip to a time. Pretty sure I pressed 2500 which should have skipped to 25m00s. I’m not sure why it shows some keys as “action is Number2” and others as “action is JumpSMS2”. Maybe this is a clue or maybe this makes sense to you.

During this sequence, no overlay appeared to show the skip-to time and it did not skip. I ended by pressing “play/pause” and then “stop” as you can see, which behaved as expected.

2019-04-25 00:03:28.636 T:1746060000   DEBUG: PushCecKeypress - received key cd duration 0 (rep:0 size:0)
2019-04-25 00:03:28.636 T:1746060000   DEBUG: PushCecKeypress - added key cd
2019-04-25 00:03:28.646 T:1915676208   DEBUG: HandleKey: 205 (0xcd, obc50) pressed, action is Number2
2019-04-25 00:03:28.649 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:28.927 T:1746060000   DEBUG: Previous line repeats 4 times.
2019-04-25 00:03:28.927 T:1746060000   DEBUG: PushCecKeypress - received key cd duration 0 (rep:0 size:0)
2019-04-25 00:03:28.927 T:1746060000   DEBUG: PushCecKeypress - added key cd
2019-04-25 00:03:28.930 T:1915676208   DEBUG: HandleKey: 205 (0xcd, obc50) pressed, action is JumpSMS2
2019-04-25 00:03:28.953 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:28.993 T:1746060000   DEBUG: Previous line repeats 3 times.
2019-04-25 00:03:28.994 T:1746060000   DEBUG: PushCecKeypress - received key cd duration 359 (rep:0 size:0)
2019-04-25 00:03:28.994 T:1746060000   DEBUG: PushCecKeypress - ignored key cd
2019-04-25 00:03:29.032 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:29.295 T:1746060000   DEBUG: Previous line repeats 5 times.
2019-04-25 00:03:29.295 T:1746060000   DEBUG: PushCecKeypress - received key ca duration 0 (rep:0 size:0)
2019-04-25 00:03:29.295 T:1746060000   DEBUG: PushCecKeypress - added key ca
2019-04-25 00:03:29.296 T:1915676208   DEBUG: HandleKey: 202 (0xca, obc53) pressed, action is JumpSMS5
2019-04-25 00:03:29.336 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:29.523 T:1746060000   DEBUG: Previous line repeats 6 times.
2019-04-25 00:03:29.523 T:1746060000   DEBUG: PushCecKeypress - received key ca duration 228 (rep:0 size:0)
2019-04-25 00:03:29.524 T:1746060000   DEBUG: PushCecKeypress - ignored key ca
2019-04-25 00:03:29.566 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:30.314 T:1746060000   DEBUG: Previous line repeats 17 times.
2019-04-25 00:03:30.314 T:1746060000   DEBUG: PushCecKeypress - received key cf duration 0 (rep:0 size:0)
2019-04-25 00:03:30.314 T:1746060000   DEBUG: PushCecKeypress - added key cf
2019-04-25 00:03:30.330 T:1915676208   DEBUG: HandleKey: 207 (0xcf, obc48) pressed, action is Number0
2019-04-25 00:03:30.365 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:30.541 T:1746060000   DEBUG: Previous line repeats 3 times.
2019-04-25 00:03:30.541 T:1746060000   DEBUG: PushCecKeypress - received key cf duration 227 (rep:0 size:0)
2019-04-25 00:03:30.541 T:1746060000   DEBUG: PushCecKeypress - ignored key cf
2019-04-25 00:03:30.570 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:30.783 T:1746060000   DEBUG: Previous line repeats 6 times.
2019-04-25 00:03:30.784 T:1746060000   DEBUG: PushCecKeypress - received key cf duration 0 (rep:0 size:0)
2019-04-25 00:03:30.784 T:1746060000   DEBUG: PushCecKeypress - added key cf
2019-04-25 00:03:30.796 T:1915676208   DEBUG: HandleKey: 207 (0xcf, obc48) pressed, action is Number0
2019-04-25 00:03:30.848 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:31.011 T:1746060000   DEBUG: Previous line repeats 7 times.
2019-04-25 00:03:31.011 T:1746060000   DEBUG: PushCecKeypress - received key cf duration 227 (rep:0 size:0)
2019-04-25 00:03:31.011 T:1746060000   DEBUG: PushCecKeypress - ignored key cf
2019-04-25 00:03:31.182 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:36.894 T:1746060000   DEBUG: Previous line repeats 137 times.
2019-04-25 00:03:36.895 T:1746060000   DEBUG: PushCecKeypress - received key ea duration 0 (rep:0 size:0)
2019-04-25 00:03:36.895 T:1746060000   DEBUG: PushCecKeypress - added key ea
2019-04-25 00:03:36.896 T:1915676208   DEBUG: HandleKey: homepage (0xea) pressed, action is PlayPause
2019-04-25 00:03:36.898 T:1915663072   DEBUG: CAnnouncementManager - Announcement: OnPause from xbmc
2019-04-25 00:03:36.898 T:1915663072   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPause
2019-04-25 00:03:36.898 T:1915676208   DEBUG: ------ Window Init (DialogSeekBar.xml) ------
2019-04-25 00:03:36.921 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:36.923 T:1354748640   DEBUG: CDVDAudio::Pause - pausing audio stream
2019-04-25 00:03:36.943 T:1371534048   DEBUG: CMMALPool::Configure pool:0x67549ab0 1920x1080 (1920x1088) pix:-1 size:128 fmt:OPQV
2019-04-25 00:03:37.122 T:1746060000   DEBUG: Previous line repeats 1 times.
2019-04-25 00:03:37.122 T:1746060000   DEBUG: PushCecKeypress - received key ea duration 228 (rep:0 size:0)
2019-04-25 00:03:37.122 T:1746060000   DEBUG: PushCecKeypress - ignored key ea
2019-04-25 00:03:37.313 T:1762845408   DEBUG: Thread JobWorker 1762845408 terminating (autodelete)
2019-04-25 00:03:37.313 T:1771238112   DEBUG: Thread JobWorker 1771238112 terminating (autodelete)
2019-04-25 00:03:37.314 T:1559225056   DEBUG: Thread JobWorker 1559225056 terminating (autodelete)
2019-04-25 00:03:37.429 T:1655681760 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
2019-04-25 00:03:37.431 T:1655681760   DEBUG: CPtsTracker: pattern lost on diff 0.000000, number of losses 1
2019-04-25 00:03:37.934 T:1655681760 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
2019-04-25 00:03:40.355 T:1746060000 WARNING: Previous line repeats 4 times.
2019-04-25 00:03:40.356 T:1746060000   DEBUG: PushCecKeypress - received key e0 duration 1000 (rep:0 size:0)
2019-04-25 00:03:40.356 T:1746060000   DEBUG: PushCecKeypress - added key e0
2019-04-25 00:03:40.363 T:1915676208   DEBUG: HandleKey: guide (0xe0) pressed, action is Stop
#6

And with more testing, I feel like a fool for missing something. I’m not sure if this behaviour has changed since I first noticed this problem or maybe I missed it due to the lack of visual feedback. I now notice that if I rapidly enter the time and press “Select” that it DOES skip to the correct time. It just doesn’t display the overlay any more.

As I said, I’m not sure if this was the behaviour all along or if it has changed since I first reported it. The lack of the overlay showing is still a problem because of the unreliability of the IR remote. You really need the visual feedback to confirm that you have entered the time correctly as missed keys and repeated keys are a frequent problem with this kind of remote.

Thanks again for your time.