Kodi:kodi:xxx:stop channel ends up in wrong state

OMR · September 5, 2017, 5:19pm

@cweitkamp, another issue (last that I know of). Running Snapshot #1031

When changing PVR Live TV channel during playback from the PVR Guide, the :stop channel ends up in the wrong state. The :stop state is ON while live TV is streaming the new channel. Here is a debug log from a channel switch:
Note the output from my Rule in there:

rule "kodiStops" 
when
        Item KodiStopped changed 
then 
    logInfo("Kodi", "KodiStopped.state=" + KodiStopped.state);
end

2017-09-05 18:58:29.968 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22909,"jsonrpc":"2.0","result":[{"playerid":1,"type":"video"}]}
2017-09-05 18:58:29.968 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22909,"jsonrpc":"2.0","result":[{"playerid":1,"type":"video"}]}
2017-09-05 18:58:29.969 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22909,"jsonrpc":"2.0","result":[{"playerid":1,"type":"video"}]}
2017-09-05 18:58:29.969 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22910,"method":"Player.GetProperties","params":{"playerid":1,"properties":["speed","position"]}}
2017-09-05 18:58:29.970 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22910,"jsonrpc":"2.0","result":{"position":-1,"speed":1}}
2017-09-05 18:58:29.970 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22910,"jsonrpc":"2.0","result":{"position":-1,"speed":1}}
2017-09-05 18:58:29.971 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22910,"jsonrpc":"2.0","result":{"position":-1,"speed":1}}
2017-09-05 18:58:29.981 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22911,"method":"Player.GetItem","params":{"playerid":1,"properties":["title","album","artist","director","thumbnail","file","fanart","showtitle","streamdetails","channel","channeltype"]}}
2017-09-05 18:58:29.982 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22911,"jsonrpc":"2.0","result":{"item":{"channel":"TV Norge HD","channeltype":"tv","director":"","fanart":"","id":124,"label":"TV Norge HD","thumbnail":"","title":"The Big Bang Theory","type":"channel"}}}
2017-09-05 18:58:29.982 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22911,"jsonrpc":"2.0","result":{"item":{"channel":"TV Norge HD","channeltype":"tv","director":"","fanart":"","id":124,"label":"TV Norge HD","thumbnail":"","title":"The Big Bang Theory","type":"channel"}}}
2017-09-05 18:58:29.983 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22911,"jsonrpc":"2.0","result":{"item":{"channel":"TV Norge HD","channeltype":"tv","director":"","fanart":"","id":124,"label":"TV Norge HD","thumbnail":"","title":"The Big Bang Theory","type":"channel"}}}
2017-09-05 18:58:34.875 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"jsonrpc":"2.0","method":"Player.OnStop","params":{"data":{"end":true,"item":{"channeltype":"tv","id":124,"title":"TV Norge HD","type":"channel"}},"sender":"xbmc"}}
2017-09-05 18:58:34.875 [DEBUG] [i.internal.protocol.KodiClientSocket] - Event received from server: {"jsonrpc":"2.0","method":"Player.OnStop","params":{"data":{"end":true,"item":{"channeltype":"tv","id":124,"title":"TV Norge HD","type":"channel"}},"sender":"xbmc"}}
2017-09-05 18:58:34.875 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"jsonrpc":"2.0","method":"Player.OnPlay","params":{"data":{"item":{"channeltype":"tv","id":116,"title":"NRK1","type":"channel"},"player":{"playerid":1,"speed":1}},"sender":"xbmc"}}
2017-09-05 18:58:34.876 [DEBUG] [i.internal.protocol.KodiClientSocket] - Event received from server: {"jsonrpc":"2.0","method":"Player.OnPlay","params":{"data":{"item":{"channeltype":"tv","id":116,"title":"NRK1","type":"channel"},"player":{"playerid":1,"speed":1}},"sender":"xbmc"}}
2017-09-05 18:58:34.887 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22912,"method":"Player.GetItem","params":{"playerid":1,"properties":["title","album","artist","director","thumbnail","file","fanart","showtitle","streamdetails","channel","channeltype"]}}
2017-09-05 18:58:34.895 [INFO ] [.eclipse.smarthome.model.script.Kodi] - KodiStopped.state=ON
2017-09-05 18:58:34.925 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22912,"jsonrpc":"2.0","result":{"item":{"channel":"NRK1","channeltype":"tv","director":"","fanart":"","id":116,"label":"NRK1","thumbnail":"","title":"Distriktsnyheter","type":"channel"}}}
2017-09-05 18:58:34.926 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22912,"jsonrpc":"2.0","result":{"item":{"channel":"NRK1","channeltype":"tv","director":"","fanart":"","id":116,"label":"NRK1","thumbnail":"","title":"Distriktsnyheter","type":"channel"}}}
2017-09-05 18:58:34.926 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22912,"jsonrpc":"2.0","result":{"item":{"channel":"NRK1","channeltype":"tv","director":"","fanart":"","id":116,"label":"NRK1","thumbnail":"","title":"Distriktsnyheter","type":"channel"}}}
2017-09-05 18:58:34.929 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22913,"method":"Application.GetProperties","params":{"properties":["version","name"]}}
2017-09-05 18:58:34.930 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22913,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:34.930 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22913,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:34.930 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22913,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:34.947 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22914,"method":"Application.GetProperties","params":{"properties":["version","name"]}}
2017-09-05 18:58:34.948 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22914,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:34.948 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22914,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:34.948 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22914,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:37.558 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22915,"method":"Application.GetProperties","params":{"properties":["version","name"]}}
2017-09-05 18:58:37.559 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22915,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:37.560 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22915,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:37.560 [DEBUG] [i.internal.protocol.KodiClientSocket] - callMethod returns {"id":22915,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:47.561 [DEBUG] [i.internal.protocol.KodiClientSocket] - send message: {"jsonrpc":"2.0","id":22916,"method":"Application.GetProperties","params":{"properties":["version","name"]}}
2017-09-05 18:58:47.562 [DEBUG] [i.internal.protocol.KodiClientSocket] - Message received from server: {"id":22916,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}
2017-09-05 18:58:47.562 [DEBUG] [i.internal.protocol.KodiClientSocket] - Response received from server: {"id":22916,"jsonrpc":"2.0","result":{"name":"Kodi","version":{"major":17,"minor":3,"revision":"20170524-147cec4","tag":"stable"}}}

cweitkamp · September 5, 2017, 8:36pm

I will try to reproduce it. Thanks for testing and reporting.

cweitkamp · September 7, 2017, 7:33am

Hey Ole,

I cannot reproduce your situation. You mentioned to use DVBLogic, right? I tested with IPTV Simple Client. I assume your PVR TV Plugin (DVBLink) sends a “Player.OnStop” event directly followed by a “Player.OnPlay” event whereas IPTV Simple Client doesn’t.