Tvheadend

Hi,

This forum is usually in English, I hope that is no problem for you.

Does the debug-log at the bottom of tvheadend show any messages that might give a clue ? ( check by opening this log and enabling debug-mode and then start watching a channel). It will tell you the status of decrypting, and might give some clues.

You say 'it stops' , you mean the entire video stops ? Again, what does the debug-log show when this happens?

Alex.

Süheyl Karakas wrote:

Hallo allemaal!

Ik hoop dat iemand mij kan helpen met het configureren van mijn omgeving.

Hierbij mijn specs:

AMD Phenom II X6 1045T
ASRock 890GX Extreme3
8GB RAM
EVGA GeForce GT 610 2GB
Digital Devices Cine C/T V6

Ik draai de laatste versie van XBMCbuntu. Ik gebruik oscam in combinatie met Tvheadend. De verbinding met de server lijkt ok (in eerste instantie).
De niet gecodeerde zender worden middels Tveadend moeiteloos doorgegeven aan XBMC. Ook werkt mijn EPG.

Wanneer ik een gecodeerde zender wil bekijken heb ik de eerste 15-30 seconden alleen beeld, geen geluid. Daarna stopt het.

Waar kan dit aan liggen?

Hi Alex,<

I´m sorry, didn´t know it was English. But that´s fine with me.

There are some channels which are not scrambled. They work fine. When I tune to a scrambled channel it works fine for some seconds, but after that the screen freezes.
Sometimes I only get video and no sound.

Here's some logging:

Hope you can help me.

System log
Loglevel debug: enabled
Aug 29 18:25:34.253 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "Nederland 1" 
Aug 29 18:25:34.261 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T554000000" for service "DDbridge_01/554,000 kHz/FOX Sports Informatie" 
Aug 29 18:25:34.261 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T554000000" without stealing for service "DDbridge_01/554,000 kHz/FOX Sports Informatie" 
Aug 29 18:25:34.261 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:25:34.262 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:25:34.262 dvb: "/dev/dvb/adapter0" tuning to "554,000 kHz" (Transport start)
Aug 29 18:25:34.262 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "FOX Sports Informatie", weight: 150, adapter: "DDbridge_01", network: "", mux: "554,000 kHz", provider: "Ziggo", service: "FOX Sports Informatie", quality: 100
Aug 29 18:25:34.576 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:25:34.576 viasat_baltic: install table handlers
Aug 29 18:25:34.576 uk_freesat: install table handlers
Aug 29 18:25:34.576 eit: install table handlers
Aug 29 18:25:34.597 Service: DDbridge_01/554,000 kHz/FOX Sports Informatie: Status changed to [Hardware input] 
Aug 29 18:25:34.597 Service: DDbridge_01/554,000 kHz/FOX Sports Informatie: Status changed to [Hardware input] [Input on service] 
Aug 29 18:25:34.597 Service: DDbridge_01/554,000 kHz/FOX Sports Informatie: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:25:34.598 eit: begin processing
Aug 29 18:25:34.777 Service: DDbridge_01/554,000 kHz/FOX Sports Informatie: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] 
Aug 29 18:25:37.619 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "FOX Sports Informatie" 
Aug 29 18:25:37.625 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T794000000" for service "DDbridge_01/794,000 kHz/Nederland 1 HD" 
Aug 29 18:25:37.625 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T794000000" without stealing for service "DDbridge_01/794,000 kHz/Nederland 1 HD" 
Aug 29 18:25:37.625 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:25:37.626 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:25:37.626 eit: processing cancelled
Aug 29 18:25:37.626 dvb: "/dev/dvb/adapter0" tuning to "794,000 kHz" (Transport start)
Aug 29 18:25:37.626 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "Nederland 1 HD", weight: 150, adapter: "DDbridge_01", network: "", mux: "794,000 kHz", provider: "Ziggo", service: "Nederland 1 HD", quality: 100
Aug 29 18:25:37.940 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:25:37.940 viasat_baltic: install table handlers
Aug 29 18:25:37.940 uk_freesat: install table handlers
Aug 29 18:25:37.940 eit: install table handlers
Aug 29 18:25:37.968 Service: DDbridge_01/794,000 kHz/Nederland 1 HD: Status changed to [Hardware input] 
Aug 29 18:25:37.968 Service: DDbridge_01/794,000 kHz/Nederland 1 HD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:25:37.968 Service: DDbridge_01/794,000 kHz/Nederland 1 HD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:25:37.969 Service: DDbridge_01/794,000 kHz/Nederland 1 HD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] 
Aug 29 18:25:37.970 eit: begin processing
Aug 29 18:25:58.002 epg: expire event 125454 from HBO 2
Aug 29 18:25:58.002 epg: now/next 126578/127704 set on HBO 2
Aug 29 18:25:58.002 epg: arm channel timer  1377801096 for HBO 2
Aug 29 18:25:58.002 epg: inform HTSP of now event change on HBO 2
Aug 29 18:25:58.002 epg: expire event 126222 from HBO 2 HD
Aug 29 18:25:58.002 epg: now/next 127120/128312 set on HBO 2 HD
Aug 29 18:25:58.002 epg: arm channel timer  1377801096 for HBO 2 HD
Aug 29 18:25:58.002 epg: inform HTSP of now event change on HBO 2 HD
Aug 29 18:26:00.002 epg: expire event 127550 from Baby TV
Aug 29 18:26:00.002 epg: now/next 127552/127554 set on Baby TV
Aug 29 18:26:00.002 epg: arm channel timer  1377795600 for Baby TV
Aug 29 18:26:00.002 epg: inform HTSP of now event change on Baby TV
Aug 29 18:26:22.957 eit: processing complete
Aug 29 18:26:25.595 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "Nederland 1 HD" 
Aug 29 18:26:25.605 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/Omroep Flevoland" -- not enabled
Aug 29 18:26:25.605 subscription: No transponder available for subscription "192.168.1.26 [ xbmc | XBMC Media Center ]" to channel "Omroep Flevoland" 
Aug 29 18:26:27.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/Omroep Flevoland" -- not enabled
Aug 29 18:26:29.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/Omroep Flevoland" -- not enabled
Aug 29 18:26:31.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/Omroep Flevoland" -- not enabled
Aug 29 18:26:33.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/Omroep Flevoland" -- not enabled
Aug 29 18:26:35.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/Omroep Flevoland" -- not enabled
Aug 29 18:26:36.130 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "Omroep Flevoland" 
Aug 29 18:26:36.141 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/TV Gelderland" -- not enabled
Aug 29 18:26:36.141 subscription: No transponder available for subscription "192.168.1.26 [ xbmc | XBMC Media Center ]" to channel "TV Gelderland" 
Aug 29 18:26:36.975 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "TV Gelderland" 
Aug 29 18:26:36.985 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T730000000" for service "DDbridge_01/730,000 kHz/TV Oost" 
Aug 29 18:26:36.985 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T730000000" without stealing for service "DDbridge_01/730,000 kHz/TV Oost" 
Aug 29 18:26:36.985 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:26:36.986 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:26:36.986 dvb: "/dev/dvb/adapter0" tuning to "730,000 kHz" (Transport start)
Aug 29 18:26:36.986 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "TV Oost", weight: 150, adapter: "DDbridge_01", network: "", mux: "730,000 kHz", provider: "Ziggo", service: "TV Oost", quality: 100
Aug 29 18:26:37.300 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:26:37.300 viasat_baltic: install table handlers
Aug 29 18:26:37.300 uk_freesat: install table handlers
Aug 29 18:26:37.300 eit: install table handlers
Aug 29 18:26:37.328 Service: DDbridge_01/730,000 kHz/TV Oost: Status changed to [Hardware input] 
Aug 29 18:26:37.328 Service: DDbridge_01/730,000 kHz/TV Oost: Status changed to [Hardware input] [Input on service] 
Aug 29 18:26:37.328 Service: DDbridge_01/730,000 kHz/TV Oost: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:26:37.328 Service: DDbridge_01/730,000 kHz/TV Oost: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] 
Aug 29 18:26:37.329 eit: begin processing
Aug 29 18:26:37.674 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "TV Oost" 
Aug 29 18:26:37.685 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/TV Drenthe" -- not enabled
Aug 29 18:26:37.685 subscription: No transponder available for subscription "192.168.1.26 [ xbmc | XBMC Media Center ]" to channel "TV Drenthe" 
Aug 29 18:26:39.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/TV Drenthe" -- not enabled
Aug 29 18:26:41.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/TV Drenthe" -- not enabled
Aug 29 18:26:42.002 eit: processing complete
Aug 29 18:26:42.002 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:26:42.003 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:26:42.003 eit: processing cancelled
Aug 29 18:26:42.003 dvb: "/dev/dvb/adapter0" tuning to "356,000 kHz" (Autoscan)
Aug 29 18:26:43.000 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/TV Drenthe" -- not enabled
Aug 29 18:26:45.000 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Skipping "DDbridge_01/730,000 kHz/TV Drenthe" -- not enabled
Aug 29 18:26:46.253 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "TV Drenthe" 
Aug 29 18:26:46.326 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:26:46.326 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:26:46.326 dvb: "/dev/dvb/adapter0" tuning to "610,000 kHz" (Transport start)
Aug 29 18:26:46.326 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:26:46.327 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "Veronica/Disney XD", weight: 150, adapter: "DDbridge_01", network: "", mux: "610,000 kHz", provider: "Ziggo", service: "Veronica/Disney XD", quality: 100
Aug 29 18:26:46.644 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:26:46.644 viasat_baltic: install table handlers
Aug 29 18:26:46.644 uk_freesat: install table handlers
Aug 29 18:26:46.644 eit: install table handlers
Aug 29 18:26:46.672 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:26:46.672 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:26:46.672 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:26:46.673 eit: begin processing
Aug 29 18:26:46.911 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:26:46.911 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20176)
Aug 29 18:26:46.911 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20177)
Aug 29 18:26:47.239 cwc: Saving prefered PID 5001
Aug 29 18:26:47.239 cwc: Received ECM reply (PID 5001) for service "Veronica/Disney XD" even: dc.27.b8.bb.d4.be.22.b4 odd: 2f.ce.25.22.1d.89.01.a7 (seqno: 20176 Req delay: 328 ms)
Aug 29 18:26:47.239 cwc: Obtained key for service "Veronica/Disney XD" in 328 ms, from 192.168.1.26:15050
Aug 29 18:26:47.310 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20178)
Aug 29 18:26:47.310 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20179)
Aug 29 18:26:47.604 TS: DDbridge_01/610,000 kHz/Veronica/Disney XD: TELETEXT  #314: Continuity counter error
Aug 29 18:26:47.604 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] 
Aug 29 18:26:47.834 cwc: Received ECM reply (PID 5001) for service "Veronica/Disney XD" even: 62.4e.83.33.ba.1d.11.e8 odd: 2f.ce.25.22.1d.89.01.a7 (seqno: 20178 Req delay: 524 ms)
Aug 29 18:26:48.067 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20179 Req delay: 756 ms)
Aug 29 18:26:48.067 cwc: Got unexpected ECM reply (seqno: 20177)
Aug 29 18:26:48.067 cwc: After got unexpected (ct->cs_okchannel: 5001)
Aug 29 18:27:00.002 epg: expire event 127612 from Canvas HD
Aug 29 18:27:00.002 epg: now/next 127614/127616 set on Canvas HD
Aug 29 18:27:00.002 epg: arm channel timer  1377793800 for Canvas HD
Aug 29 18:27:00.002 epg: inform HTSP of now event change on Canvas HD
Aug 29 18:27:00.002 epg: expire event 128324 from Canvas
Aug 29 18:27:00.002 epg: now/next 128326/128328 set on Canvas
Aug 29 18:27:00.002 epg: arm channel timer  1377793800 for Canvas
Aug 29 18:27:00.002 epg: inform HTSP of now event change on Canvas
Aug 29 18:27:00.002 epg: expire event 128590 from Dusk! Deluxe
Aug 29 18:27:00.002 epg: now/next 128592/128594 set on Dusk! Deluxe
Aug 29 18:27:00.002 epg: arm channel timer  1377795060 for Dusk! Deluxe
Aug 29 18:27:00.002 epg: inform HTSP of now event change on Dusk! Deluxe
Aug 29 18:27:12.304 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20180)
Aug 29 18:27:12.304 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20181)
Aug 29 18:27:12.306 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20180 Req delay: 0 ms)
Aug 29 18:27:12.308 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20181 Req delay: 4 ms)
Aug 29 18:27:12.308 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20181 Req delay: 4 ms)
Aug 29 18:27:12.330 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] [No access] 
Aug 29 18:27:18.327 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "Veronica/Disney XD" 
Aug 29 18:27:18.332 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T474000000" for service "DDbridge_01/474,000 kHz/13TH STREET" 
Aug 29 18:27:18.332 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T474000000" without stealing for service "DDbridge_01/474,000 kHz/13TH STREET" 
Aug 29 18:27:18.332 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:27:18.332 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:27:18.332 eit: processing cancelled
Aug 29 18:27:18.332 dvb: "/dev/dvb/adapter0" tuning to "474,000 kHz" (Transport start)
Aug 29 18:27:18.333 cwc: DDbridge_01/474,000 kHz/13TH STREET using CWC 192.168.1.26:15050
Aug 29 18:27:18.333 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "13TH STREET", weight: 150, adapter: "DDbridge_01", network: "", mux: "474,000 kHz", provider: "Ziggo", service: "13TH STREET", quality: 100
Aug 29 18:27:18.644 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:27:18.644 viasat_baltic: install table handlers
Aug 29 18:27:18.644 uk_freesat: install table handlers
Aug 29 18:27:18.644 eit: install table handlers
Aug 29 18:27:18.665 Service: DDbridge_01/474,000 kHz/13TH STREET: Status changed to [Hardware input] 
Aug 29 18:27:18.665 Service: DDbridge_01/474,000 kHz/13TH STREET: Status changed to [Hardware input] [Input on service] 
Aug 29 18:27:18.665 eit: begin processing
Aug 29 18:27:18.805 Service: DDbridge_01/474,000 kHz/13TH STREET: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:27:18.805 cwc: Insert new ECM (PID 5008) for service "13TH STREET" 
Aug 29 18:27:18.805 cwc: Sending ECM (PID 5008) section=0/1, for service "13TH STREET" (seqno: 20182)
Aug 29 18:27:18.805 cwc: Sending ECM (PID 5008) section=1/1, for service "13TH STREET" (seqno: 20183)
Aug 29 18:27:19.132 cwc: Saving prefered PID 5008
Aug 29 18:27:19.132 cwc: Received ECM reply (PID 5008) for service "13TH STREET" even: 9a.ae.3b.83.23.ff.1a.3c odd: a0.ef.42.d1.63.92.4b.40 (seqno: 20182 Req delay: 328 ms)
Aug 29 18:27:19.132 cwc: Obtained key for service "13TH STREET" in 328 ms, from 192.168.1.26:15050
Aug 29 18:27:19.185 Service: DDbridge_01/474,000 kHz/13TH STREET: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] 
Aug 29 18:27:19.351 cwc: Received NOK for service "13TH STREET" (PID 5008) (seqno: 20183 Req delay: 548 ms)
Aug 29 18:27:21.018 cwc: Sending ECM (PID 5008) section=0/1, for service "13TH STREET" (seqno: 20184)
Aug 29 18:27:21.018 cwc: Sending ECM (PID 5008) section=1/1, for service "13TH STREET" (seqno: 20185)
Aug 29 18:27:21.022 cwc: Received NOK for service "13TH STREET" (PID 5008) (seqno: 20184 Req delay: 4 ms)
Aug 29 18:27:21.023 cwc: Received NOK for service "13TH STREET" (PID 5008) (seqno: 20185 Req delay: 4 ms)
Aug 29 18:27:21.023 cwc: Can not descramble service "13TH STREET", access denied (seqno: 20185 Req delay: 4 ms)
Aug 29 18:27:21.038 Service: DDbridge_01/474,000 kHz/13TH STREET: Status changed to [Hardware input] [Input on service] [Demuxed packets] [Reassembled packets] [No access] 
Aug 29 18:27:46.025 cwc: Sending ECM (PID 5008) section=0/1, for service "13TH STREET" (seqno: 20186)
Aug 29 18:27:46.025 cwc: Sending ECM (PID 5008) section=1/1, for service "13TH STREET" (seqno: 20187)
Aug 29 18:28:03.773 eit: processing complete
Aug 29 18:28:11.012 cwc: Sending ECM (PID 5008) section=0/1, for service "13TH STREET" (seqno: 20188)
Aug 29 18:28:24.133 epg: now/next 146776/127308 set on Star Gold
Aug 29 18:28:24.133 epg: arm channel timer  1377793800 for Star Gold
Aug 29 18:28:24.133 epg: inform HTSP of now event change on Star Gold
Aug 29 18:30:00.002 epg: expire event 146776 from Star Gold
Aug 29 18:30:00.002 epg: now/next 127308/128674 set on Star Gold
Aug 29 18:30:00.002 epg: arm channel timer  1377802800 for Star Gold
Aug 29 18:30:00.002 epg: inform HTSP of now event change on Star Gold
Aug 29 18:30:00.002 epg: expire event 128326 from Canvas
Aug 29 18:30:00.002 epg: now/next 128328/128330 set on Canvas
Aug 29 18:30:00.002 epg: arm channel timer  1377795600 for Canvas
Aug 29 18:30:00.002 epg: inform HTSP of now event change on Canvas
Aug 29 18:30:00.002 epg: expire event 127614 from Canvas HD
Aug 29 18:30:00.002 epg: now/next 127616/127618 set on Canvas HD
Aug 29 18:30:00.002 epg: arm channel timer  1377795600 for Canvas HD
Aug 29 18:30:00.002 epg: inform HTSP of now event change on Canvas HD
Aug 29 18:30:00.002 epg: expire event 127212 from JimJam
Aug 29 18:30:00.002 epg: now/next 127214/127216 set on JimJam
Aug 29 18:30:00.002 epg: arm channel timer  1377794100 for JimJam
Aug 29 18:30:00.002 epg: inform HTSP of now event change on JimJam
Aug 29 18:30:00.002 epg: expire event 128270 from Eén HD
Aug 29 18:30:00.002 epg: now/next 128272/128274 set on Eén HD
Aug 29 18:30:00.002 epg: arm channel timer  1377795600 for Eén HD
Aug 29 18:30:00.002 epg: inform HTSP of now event change on Eén HD
Aug 29 18:30:00.002 epg: expire event 128300 from Eén
Aug 29 18:30:00.002 epg: now/next 128302/128304 set on Eén
Aug 29 18:30:00.002 epg: arm channel timer  1377795600 for Eén
Aug 29 18:30:00.002 epg: inform HTSP of now event change on Eén
Aug 29 18:30:00.003 epg: expire event 127906 from FOX Sports 1 Eredivisie
Aug 29 18:30:00.003 epg: now/next 127908/127910 set on FOX Sports 1 Eredivisie
Aug 29 18:30:00.003 epg: arm channel timer  1377795600 for FOX Sports 1 Eredivisie
Aug 29 18:30:00.003 epg: inform HTSP of now event change on FOX Sports 1 Eredivisie
Aug 29 18:30:00.003 epg: expire event 127746 from FOX Sports 1 Eredivisie HD
Aug 29 18:30:00.003 epg: now/next 127748/127750 set on FOX Sports 1 Eredivisie HD
Aug 29 18:30:00.003 epg: arm channel timer  1377795600 for FOX Sports 1 Eredivisie HD
Aug 29 18:30:00.003 epg: inform HTSP of now event change on FOX Sports 1 Eredivisie HD
Aug 29 18:30:00.003 epg: expire event 146420 from Zappelin / Zapp 24
Aug 29 18:30:00.003 epg: now/next 146422/146424 set on Zappelin / Zapp 24
Aug 29 18:30:00.003 epg: arm channel timer  1377795300 for Zappelin / Zapp 24
Aug 29 18:30:00.003 epg: inform HTSP of now event change on Zappelin / Zapp 24
Aug 29 18:30:00.003 epg: expire event 128170 from Disney Channel
Aug 29 18:30:00.003 epg: now/next 128172/128174 set on Disney Channel
Aug 29 18:30:00.003 epg: arm channel timer  1377795300 for Disney Channel
Aug 29 18:30:00.003 epg: inform HTSP of now event change on Disney Channel
Aug 29 18:30:00.003 epg: expire event 128196 from FOXlife HD
Aug 29 18:30:00.003 epg: now/next 128198/128200 set on FOXlife HD
Aug 29 18:30:00.003 epg: arm channel timer  1377796500 for FOXlife HD
Aug 29 18:30:00.003 epg: inform HTSP of now event change on FOXlife HD
Aug 29 18:30:00.003 epg: expire event 128476 from FOXlife
Aug 29 18:30:00.003 epg: now/next 128478/128480 set on FOXlife
Aug 29 18:30:00.003 epg: arm channel timer  1377796500 for FOXlife
Aug 29 18:30:00.003 epg: inform HTSP of now event change on FOXlife
Aug 29 18:30:00.003 epg: expire event 127640 from Boomerang
Aug 29 18:30:00.003 epg: now/next 127642/127644 set on Boomerang
Aug 29 18:30:00.003 epg: arm channel timer  1377795300 for Boomerang
Aug 29 18:30:00.003 epg: inform HTSP of now event change on Boomerang
Aug 29 18:30:00.003 epg: expire event 128222 from RTL 7 HD
Aug 29 18:30:00.003 epg: now/next 128224/128226 set on RTL 7 HD
Aug 29 18:30:00.003 epg: arm channel timer  1377797400 for RTL 7 HD
Aug 29 18:30:00.003 epg: inform HTSP of now event change on RTL 7 HD
Aug 29 18:30:00.003 epg: expire event 127374 from RTL 7
Aug 29 18:30:00.003 epg: now/next 127376/127378 set on RTL 7
Aug 29 18:30:00.003 epg: arm channel timer  1377797400 for RTL 7
Aug 29 18:30:00.003 epg: inform HTSP of now event change on RTL 7
Aug 29 18:30:00.003 epg: expire event 127288 from Aljazeera English
Aug 29 18:30:00.003 epg: now/next 127290/127292 set on Aljazeera English
Aug 29 18:30:00.003 epg: arm channel timer  1377795600 for Aljazeera English
Aug 29 18:30:00.003 epg: inform HTSP of now event change on Aljazeera English
Aug 29 18:30:00.003 epg: expire event 127966 from NostalgieNet
Aug 29 18:30:00.003 epg: now/next 127968/127970 set on NostalgieNet
Aug 29 18:30:00.003 epg: arm channel timer  1377795600 for NostalgieNet
Aug 29 18:30:00.003 epg: inform HTSP of now event change on NostalgieNet
Aug 29 18:30:00.003 epg: expire event 127440 from RTL Lounge
Aug 29 18:30:00.003 epg: now/next 127442/127444 set on RTL Lounge
Aug 29 18:30:00.003 epg: arm channel timer  1377797400 for RTL Lounge
Aug 29 18:30:00.003 epg: inform HTSP of now event change on RTL Lounge
Aug 29 18:30:00.003 epg: expire event 127512 from National Geographic Channel HD
Aug 29 18:30:00.003 epg: now/next 127514/127516 set on National Geographic Channel HD
Aug 29 18:30:00.003 epg: arm channel timer  1377795600 for National Geographic Channel HD
Aug 29 18:30:00.003 epg: inform HTSP of now event change on National Geographic Channel HD
Aug 29 18:30:00.003 epg: expire event 128434 from National Geographic Channel
Aug 29 18:30:00.003 epg: now/next 128436/128438 set on National Geographic Channel
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for National Geographic Channel
Aug 29 18:30:00.004 epg: inform HTSP of now event change on National Geographic Channel
Aug 29 18:30:00.004 epg: expire event 128136 from TLC
Aug 29 18:30:00.004 epg: now/next 128138/128140 set on TLC
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for TLC
Aug 29 18:30:00.004 epg: inform HTSP of now event change on TLC
Aug 29 18:30:00.004 epg: expire event 130712 from FOX
Aug 29 18:30:00.004 epg: now/next 130714/130716 set on FOX
Aug 29 18:30:00.004 epg: arm channel timer  1377795300 for FOX
Aug 29 18:30:00.004 epg: inform HTSP of now event change on FOX
Aug 29 18:30:00.004 epg: expire event 131802 from FOX HD
Aug 29 18:30:00.004 epg: now/next 131804/131806 set on FOX HD
Aug 29 18:30:00.004 epg: arm channel timer  1377795300 for FOX HD
Aug 29 18:30:00.004 epg: inform HTSP of now event change on FOX HD
Aug 29 18:30:00.004 epg: expire event 126684 from BBC 3 / CBBC
Aug 29 18:30:00.004 epg: now/next 126686/126688 set on BBC 3 / CBBC
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for BBC 3 / CBBC
Aug 29 18:30:00.004 epg: inform HTSP of now event change on BBC 3 / CBBC
Aug 29 18:30:00.004 epg: expire event 125512 from RTL Television
Aug 29 18:30:00.004 epg: now/next 125514/125516 set on RTL Television
Aug 29 18:30:00.004 epg: arm channel timer  1377794700 for RTL Television
Aug 29 18:30:00.004 epg: inform HTSP of now event change on RTL Television
Aug 29 18:30:00.004 epg: expire event 127356 from RTL 5
Aug 29 18:30:00.004 epg: now/next 127358/127360 set on RTL 5
Aug 29 18:30:00.004 epg: arm channel timer  1377797400 for RTL 5
Aug 29 18:30:00.004 epg: inform HTSP of now event change on RTL 5
Aug 29 18:30:00.004 epg: expire event 127764 from RTL 5 HD
Aug 29 18:30:00.004 epg: now/next 127766/127768 set on RTL 5 HD
Aug 29 18:30:00.004 epg: arm channel timer  1377797400 for RTL 5 HD
Aug 29 18:30:00.004 epg: inform HTSP of now event change on RTL 5 HD
Aug 29 18:30:00.004 epg: expire event 128036 from Horse & Country TV
Aug 29 18:30:00.004 epg: now/next 128038/128040 set on Horse & Country TV
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for Horse & Country TV
Aug 29 18:30:00.004 epg: inform HTSP of now event change on Horse & Country TV
Aug 29 18:30:00.004 epg: expire event 127956 from HISTORY
Aug 29 18:30:00.004 epg: now/next 127958/127960 set on HISTORY
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for HISTORY
Aug 29 18:30:00.004 epg: inform HTSP of now event change on HISTORY
Aug 29 18:30:00.004 epg: expire event 127944 from HISTORY HD
Aug 29 18:30:00.004 epg: now/next 127946/127948 set on HISTORY HD
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for HISTORY HD
Aug 29 18:30:00.004 epg: inform HTSP of now event change on HISTORY HD
Aug 29 18:30:00.004 epg: expire event 127300 from Star Plus
Aug 29 18:30:00.004 epg: now/next 127302/127304 set on Star Plus
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for Star Plus
Aug 29 18:30:00.004 epg: inform HTSP of now event change on Star Plus
Aug 29 18:30:00.004 epg: expire event 127416 from BBC World News
Aug 29 18:30:00.004 epg: now/next 127418/127420 set on BBC World News
Aug 29 18:30:00.004 epg: arm channel timer  1377794700 for BBC World News
Aug 29 18:30:00.004 epg: inform HTSP of now event change on BBC World News
Aug 29 18:30:00.004 epg: expire event 128054 from 24 Kitchen
Aug 29 18:30:00.004 epg: now/next 128056/128058 set on 24 Kitchen
Aug 29 18:30:00.004 epg: arm channel timer  1377795600 for 24 Kitchen
Aug 29 18:30:00.004 epg: inform HTSP of now event change on 24 Kitchen
Aug 29 18:30:00.004 epg: expire event 128444 from CNN International
Aug 29 18:30:00.004 epg: now/next 128446/128448 set on CNN International
Aug 29 18:30:00.004 epg: arm channel timer  1377794700 for CNN International
Aug 29 18:30:00.004 epg: inform HTSP of now event change on CNN International
Aug 29 18:30:00.004 epg: expire event 127822 from Humor TV 24
Aug 29 18:30:00.005 epg: now/next 127824/127826 set on Humor TV 24
Aug 29 18:30:00.005 epg: arm channel timer  1377795600 for Humor TV 24
Aug 29 18:30:00.005 epg: inform HTSP of now event change on Humor TV 24
Aug 29 18:30:00.005 epg: expire event 128412 from Investigation Discovery
Aug 29 18:30:00.005 epg: now/next 128414/128416 set on Investigation Discovery
Aug 29 18:30:00.005 epg: arm channel timer  1377796800 for Investigation Discovery
Aug 29 18:30:00.005 epg: inform HTSP of now event change on Investigation Discovery
Aug 29 18:30:00.005 epg: expire event 127572 from Syfy Universal
Aug 29 18:30:00.005 epg: now/next 127574/127576 set on Syfy Universal
Aug 29 18:30:00.005 epg: arm channel timer  1377796800 for Syfy Universal
Aug 29 18:30:00.005 epg: inform HTSP of now event change on Syfy Universal
Aug 29 18:30:00.005 epg: expire event 127458 from 13TH STREET
Aug 29 18:30:00.005 epg: now/next 127460/127462 set on 13TH STREET
Aug 29 18:30:00.005 epg: arm channel timer  1377796800 for 13TH STREET
Aug 29 18:30:00.005 epg: inform HTSP of now event change on 13TH STREET
Aug 29 18:30:00.005 epg: expire event 128386 from Discovery Science
Aug 29 18:30:00.005 epg: now/next 128388/128390 set on Discovery Science
Aug 29 18:30:00.005 epg: arm channel timer  1377795300 for Discovery Science
Aug 29 18:30:00.005 epg: inform HTSP of now event change on Discovery Science
Aug 29 18:30:00.005 epg: expire event 126996 from Cultura 24
Aug 29 18:30:00.005 epg: now/next 126998/127000 set on Cultura 24
Aug 29 18:30:00.005 epg: arm channel timer  1377796800 for Cultura 24
Aug 29 18:30:00.005 epg: inform HTSP of now event change on Cultura 24
Aug 29 18:30:18.002 epg: expire event 128212 from Pebble TV
Aug 29 18:30:18.002 epg: now/next 128214/128216 set on Pebble TV
Aug 29 18:30:18.002 epg: arm channel timer  1377795620 for Pebble TV
Aug 29 18:30:18.002 epg: inform HTSP of now event change on Pebble TV
Aug 29 18:30:23.002 epg: expire event 127922 from FOX Sports 3 Eredivisie
Aug 29 18:30:23.002 epg: now/next 127924/127926 set on FOX Sports 3 Eredivisie
Aug 29 18:30:23.002 epg: arm channel timer  1377794728 for FOX Sports 3 Eredivisie
Aug 29 18:30:23.002 epg: inform HTSP of now event change on FOX Sports 3 Eredivisie
Aug 29 18:30:29.002 epg: expire event 145454 from FOX Sports 5 Eredivisie
Aug 29 18:30:29.002 epg: now/next 124318/124320 set on FOX Sports 5 Eredivisie
Aug 29 18:30:29.002 epg: arm channel timer  1377795627 for FOX Sports 5 Eredivisie
Aug 29 18:30:29.002 epg: inform HTSP of now event change on FOX Sports 5 Eredivisie
Aug 29 18:34:00.002 epg: expire event 128118 from PassieTV/Dusk!
Aug 29 18:34:00.002 epg: now/next 128120/128122 set on PassieTV/Dusk!
Aug 29 18:34:00.002 epg: arm channel timer  1377794460 for PassieTV/Dusk!
Aug 29 18:34:00.002 epg: inform HTSP of now event change on PassieTV/Dusk!
Aug 29 18:34:20.122 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "13TH STREET" 
Aug 29 18:34:20.195 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:20.195 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:20.195 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:34:20.196 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:34:20.196 dvb: "/dev/dvb/adapter0" tuning to "610,000 kHz" (Transport start)
Aug 29 18:34:20.196 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:20.196 subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "Veronica/Disney XD", weight: 150, adapter: "DDbridge_01", network: "", mux: "610,000 kHz", provider: "Ziggo", service: "Veronica/Disney XD", quality: 100
Aug 29 18:34:20.512 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:34:20.512 viasat_baltic: install table handlers
Aug 29 18:34:20.512 uk_freesat: install table handlers
Aug 29 18:34:20.512 eit: install table handlers
Aug 29 18:34:20.540 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:20.540 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:20.541 eit: begin processing
Aug 29 18:34:20.646 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:20.646 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:20.647 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20201)
Aug 29 18:34:20.647 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20202)
Aug 29 18:34:20.648 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20201 Req delay: 4 ms)
Aug 29 18:34:20.868 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20202 Req delay: 224 ms)
Aug 29 18:34:20.868 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20202 Req delay: 224 ms)
Aug 29 18:34:20.885 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:22.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:23.002 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:34:23.002 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:34:23.003 eit: processing cancelled
Aug 29 18:34:23.003 dvb: "/dev/dvb/adapter0" tuning to "578,000 kHz" (Autoscan)
Aug 29 18:34:23.316 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:34:23.316 viasat_baltic: install table handlers
Aug 29 18:34:23.316 uk_freesat: install table handlers
Aug 29 18:34:23.316 eit: install table handlers
Aug 29 18:34:23.343 eit: begin processing
Aug 29 18:34:24.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:24.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:24.002 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:34:24.002 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:34:24.003 eit: processing cancelled
Aug 29 18:34:24.003 dvb: "/dev/dvb/adapter0" tuning to "610,000 kHz" (Transport start)
Aug 29 18:34:24.003 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:24.316 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:34:24.316 viasat_baltic: install table handlers
Aug 29 18:34:24.316 uk_freesat: install table handlers
Aug 29 18:34:24.316 eit: install table handlers
Aug 29 18:34:24.343 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:24.343 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:24.343 eit: begin processing
Aug 29 18:34:24.556 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:24.556 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:24.556 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20203)
Aug 29 18:34:24.556 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20204)
Aug 29 18:34:24.558 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20203 Req delay: 0 ms)
Aug 29 18:34:24.560 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20204 Req delay: 4 ms)
Aug 29 18:34:24.560 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20204 Req delay: 4 ms)
Aug 29 18:34:24.583 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:26.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:28.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:28.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:28.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:28.012 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:28.012 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:28.146 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:28.146 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:28.146 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20205)
Aug 29 18:34:28.146 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20206)
Aug 29 18:34:28.148 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20205 Req delay: 4 ms)
Aug 29 18:34:28.150 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20206 Req delay: 4 ms)
Aug 29 18:34:28.150 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20206 Req delay: 4 ms)
Aug 29 18:34:28.172 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:30.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:32.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:32.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:32.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:32.027 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:32.027 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:32.054 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:32.054 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:32.054 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20207)
Aug 29 18:34:32.054 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20208)
Aug 29 18:34:32.056 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20207 Req delay: 4 ms)
Aug 29 18:34:32.058 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20208 Req delay: 4 ms)
Aug 29 18:34:32.058 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20208 Req delay: 4 ms)
Aug 29 18:34:32.080 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:34.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:36.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:36.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:36.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:36.016 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:36.016 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:36.255 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:36.255 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:36.255 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20209)
Aug 29 18:34:36.255 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20210)
Aug 29 18:34:36.257 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20209 Req delay: 0 ms)
Aug 29 18:34:36.258 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20210 Req delay: 0 ms)
Aug 29 18:34:36.258 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20210 Req delay: 0 ms)
Aug 29 18:34:36.282 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:38.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:40.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:40.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:40.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:40.004 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:40.004 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:40.137 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:40.137 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:40.137 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20211)
Aug 29 18:34:40.137 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20212)
Aug 29 18:34:40.139 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20211 Req delay: 4 ms)
Aug 29 18:34:40.140 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20212 Req delay: 4 ms)
Aug 29 18:34:40.140 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20212 Req delay: 4 ms)
Aug 29 18:34:40.163 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:42.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:43.002 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:34:43.002 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:34:43.003 eit: processing cancelled
Aug 29 18:34:43.003 dvb: "/dev/dvb/adapter0" tuning to "372,000 kHz" (Autoscan)
Aug 29 18:34:44.000 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:44.000 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:44.000 dvb: "/dev/dvb/adapter0" tuning to "610,000 kHz" (Transport start)
Aug 29 18:34:44.000 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:44.312 dvb: /dev/dvb/adapter0 started dvr thread
Aug 29 18:34:44.312 viasat_baltic: install table handlers
Aug 29 18:34:44.312 uk_freesat: install table handlers
Aug 29 18:34:44.312 eit: install table handlers
Aug 29 18:34:44.339 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:44.339 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:44.340 eit: begin processing
Aug 29 18:34:44.446 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:44.446 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:44.446 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20213)
Aug 29 18:34:44.446 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20214)
Aug 29 18:34:44.449 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20213 Req delay: 4 ms)
Aug 29 18:34:44.451 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20214 Req delay: 8 ms)
Aug 29 18:34:44.451 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20214 Req delay: 8 ms)
Aug 29 18:34:44.473 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:46.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:48.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:48.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:48.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:48.009 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:48.009 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:48.036 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:48.036 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:48.036 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20215)
Aug 29 18:34:48.036 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20216)
Aug 29 18:34:48.038 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20215 Req delay: 0 ms)
Aug 29 18:34:48.040 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20216 Req delay: 4 ms)
Aug 29 18:34:48.040 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20216 Req delay: 4 ms)
Aug 29 18:34:48.062 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:50.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:52.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:52.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:52.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:52.024 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:52.024 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:52.237 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:52.237 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:52.237 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20217)
Aug 29 18:34:52.237 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20218)
Aug 29 18:34:52.239 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20217 Req delay: 4 ms)
Aug 29 18:34:52.241 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20218 Req delay: 4 ms)
Aug 29 18:34:52.241 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20218 Req delay: 4 ms)
Aug 29 18:34:52.263 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:54.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:56.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:56.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:34:56.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:34:56.012 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:34:56.012 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:34:56.145 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:34:56.145 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:34:56.145 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20219)
Aug 29 18:34:56.145 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20220)
Aug 29 18:34:56.148 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20219 Req delay: 4 ms)
Aug 29 18:34:56.149 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20220 Req delay: 4 ms)
Aug 29 18:34:56.150 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20220 Req delay: 4 ms)
Aug 29 18:34:56.171 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:34:58.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:35:00.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:35:00.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Probing adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" without stealing for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:35:00.002 cwc: DDbridge_01/610,000 kHz/Veronica/Disney XD using CWC 192.168.1.26:15050
Aug 29 18:35:00.002 epg: expire event 127214 from JimJam
Aug 29 18:35:00.002 epg: now/next 127216/127218 set on JimJam
Aug 29 18:35:00.002 epg: arm channel timer  1377794400 for JimJam
Aug 29 18:35:00.002 epg: inform HTSP of now event change on JimJam
Aug 29 18:35:00.002 epg: expire event 128406 from BBC 4 / CBeebies
Aug 29 18:35:00.002 epg: now/next 128408/128410 set on BBC 4 / CBeebies
Aug 29 18:35:00.002 epg: arm channel timer  1377794700 for BBC 4 / CBeebies
Aug 29 18:35:00.002 epg: inform HTSP of now event change on BBC 4 / CBeebies
Aug 29 18:35:00.002 epg: expire event 127722 from Disney Junior
Aug 29 18:35:00.002 epg: now/next 127724/127726 set on Disney Junior
Aug 29 18:35:00.002 epg: arm channel timer  1377795000 for Disney Junior
Aug 29 18:35:00.002 epg: inform HTSP of now event change on Disney Junior
Aug 29 18:35:00.002 epg: expire event 128488 from Comedy Central Family
Aug 29 18:35:00.002 epg: now/next 128490/128492 set on Comedy Central Family
Aug 29 18:35:00.002 epg: arm channel timer  1377795600 for Comedy Central Family
Aug 29 18:35:00.002 epg: inform HTSP of now event change on Comedy Central Family
Aug 29 18:35:00.002 epg: expire event 128342 from Ketnet/OP12
Aug 29 18:35:00.002 epg: now/next 128344/128346 set on Ketnet/OP12
Aug 29 18:35:00.002 epg: arm channel timer  1377794700 for Ketnet/OP12
Aug 29 18:35:00.002 epg: inform HTSP of now event change on Ketnet/OP12
Aug 29 18:35:00.002 epg: expire event 127326 from RTL 4
Aug 29 18:35:00.002 epg: now/next 127328/127330 set on RTL 4
Aug 29 18:35:00.002 epg: arm channel timer  1377797400 for RTL 4
Aug 29 18:35:00.002 epg: inform HTSP of now event change on RTL 4
Aug 29 18:35:00.002 epg: expire event 128016 from RTL 4 HD
Aug 29 18:35:00.002 epg: now/next 128018/128020 set on RTL 4 HD
Aug 29 18:35:00.002 epg: arm channel timer  1377797400 for RTL 4 HD
Aug 29 18:35:00.002 epg: inform HTSP of now event change on RTL 4 HD
Aug 29 18:35:00.002 epg: expire event 127754 from Discovery Channel HD
Aug 29 18:35:00.002 epg: now/next 127756/127758 set on Discovery Channel HD
Aug 29 18:35:00.002 epg: arm channel timer  1377795900 for Discovery Channel HD
Aug 29 18:35:00.002 epg: inform HTSP of now event change on Discovery Channel HD
Aug 29 18:35:00.002 epg: expire event 128426 from Discovery Channel
Aug 29 18:35:00.002 epg: now/next 128428/128430 set on Discovery Channel
Aug 29 18:35:00.002 epg: arm channel timer  1377795900 for Discovery Channel
Aug 29 18:35:00.002 epg: inform HTSP of now event change on Discovery Channel
Aug 29 18:35:00.002 epg: expire event 127992 from Nick Jr.
Aug 29 18:35:00.002 epg: now/next 127994/127996 set on Nick Jr.
Aug 29 18:35:00.002 epg: arm channel timer  1377795600 for Nick Jr.
Aug 29 18:35:00.002 epg: inform HTSP of now event change on Nick Jr.
Aug 29 18:35:00.027 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] 
Aug 29 18:35:00.027 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] 
Aug 29 18:35:00.053 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] 
Aug 29 18:35:00.053 cwc: Insert new ECM (PID 5001) for service "Veronica/Disney XD" 
Aug 29 18:35:00.053 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20221)
Aug 29 18:35:00.053 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20222)
Aug 29 18:35:00.055 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20221 Req delay: 4 ms)
Aug 29 18:35:00.057 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20222 Req delay: 4 ms)
Aug 29 18:35:00.057 cwc: Can not descramble service "Veronica/Disney XD", access denied (seqno: 20222 Req delay: 4 ms)
Aug 29 18:35:00.080 Service: DDbridge_01/610,000 kHz/Veronica/Disney XD: Status changed to [Hardware input] [Input on service] [Demuxed packets] [No access] 
Aug 29 18:35:02.002 Service: Subscription "192.168.1.26 [ xbmc | XBMC Media Center ]": Adding adapter "_dev_dvb_adapter0_STV0367_DVB_C_DVB_T610000000" for service "DDbridge_01/610,000 kHz/Veronica/Disney XD" 
Aug 29 18:35:03.002 dvb: /dev/dvb/adapter0 stopping thread
Aug 29 18:35:03.002 dvb: /dev/dvb/adapter0 stopped thread
Aug 29 18:35:03.003 eit: processing cancelled
Aug 29 18:35:03.003 dvb: "/dev/dvb/adapter0" tuning to "514,000 kHz" (Autoscan)

Hi,

One other thing to check: Does this happen only when watching via the xbmc-plugin, or also via the webinterface?
( so , basically, can you watch both unencrypted and encrypted channels using the webinterface?)

Alex.

( ps: on a side note: you probably don't need ukfreesat ota-grabber, so you can disable that one at least)

Hi Alex,

I haven't tried the webinterface... I'm running XBMCbuntu so I have no webinterface

Süheyl Karakas wrote:

Hi Alex,

I haven't tried the webinterface... I'm running XBMCbuntu so I have no webinterface

Mmm, i do not know what xbmcubuntu is, but you use tvheadend and tvheadend has a webinterface.

Go to <ip of your tvheadendmachine> :9981/extjs.html

Does this show anything?

I'm sorry, I misunderstood. The logging is from the webinterface of tvheadend. I'll have to install VLC-player first. One moment

I had to install Mozilla first, but there I don´t see anything at all...

Loglevel debug: enabled

Aug 29 19:01:54.047 HTTP: 192.168.1.28: /stream/channelid/253 -- 401

Aug 29 19:01:54.065 HTTP: 192.168.1.28: /stream/channelid/253 -- 401

Aug 29 19:02:00.002 epg: expire event 128330 from Canvas

Aug 29 19:02:00.002 epg: now/next 128332/129534 set on Canvas

Aug 29 19:02:00.002 epg: arm channel timer @ 1377799200 for Canvas

Aug 29 19:02:00.002 epg: inform HTSP of now event change on Canvas

Aug 29 19:02:00.002 epg: expire event 127618 from Canvas HD

Aug 29 19:02:00.002 epg: now/next 127620/128962 set on Canvas HD

Aug 29 19:02:00.002 epg: arm channel timer @ 1377799200 for Canvas HD

Aug 29 19:02:00.002 epg: inform HTSP of now event change on Canvas HD

Aug 29 19:02:13.880 HTTP: 192.168.1.28: /stream/channelid/1 -- 401

Aug 29 19:02:13.897 HTTP: 192.168.1.28: /stream/channelid/1 -- 401

Mmm, strange, so using the webinterface you cant see anything ?(even not the unencrypted channels?).
Also , you can go to that IP address from any other computer on your local lan, so you can try to watch video from some other machine.

I would say that should work anyhow, but can it be that somehow on XBMCbuntu the xbmc-client is first?

I've did that from another client. I have no browser installed on the XBMC machine. I'm not sure if the XBMC client is indeed priority 1. I'm new into Linux, so I don't know if and how to install a browser there.

Ow, I just looked at you logfile. It is full with lines like:

Aug 29 18:27:21.023 cwc: Can not descramble service "13TH STREET", access denied (seqno: 20185 Req delay: 4 ms

And
Aug 29 18:34:20.647 cwc: Sending ECM (PID 5001) section=0/1, for service "Veronica/Disney XD" (seqno: 20201)
Aug 29 18:34:20.647 cwc: Sending ECM (PID 5001) section=1/1, for service "Veronica/Disney XD" (seqno: 20202)
Aug 29 18:34:20.648 cwc: Received NOK for service "Veronica/Disney XD" (PID 5001) (seqno: 20201 Req delay: 4 ms)

( NOK =, i guess, ' Not OK ')

So probably somehow Oscam does not work, or your card is not valid/updated/activated.

- In your webinterface, under CSA, codeword client, is the info needed to connect to oscam there? And is the line 'green'?
- Maybe best would be to also look at the oscam logfile.
- Is your code-card updated ? If not, maybe change cards with the one in your 'provider-decoder' to check.

Alex.

Ps what provider do you have?

Sorry, I had to do some shopping, but yes the line is green. The card is active.

Is it possible that it doesn't descramble because the server is a CCcam server and I'm using Oscam? I tried to use CCcam but then I couldn't get the line green. That's why I reverted to Oscam.
My brother is connecting to another server and I use the same configuration but connect to a different server.

The fact that the line is green is a sign that at least tvheadend van connect to the oscam (or whatever cam) server. ( not necessarily that valid codes are generated for a certain request)

So, best is now to check the actual log of the cam-server (cccam in your case), since apparently no valid decryption key is obtained from that server.

This can have to do with all kinds of things in the cam-server, f.e.:
- wrong settings ( they vary from provider to provider)
- invalid card

So some questions might be:
- what provider do you have ?
- Did you use the cccam settings specific to that card?
- Is the card read at all ( what reader do you use?)
- Are you sure the card is valid and activated?( check using a regular decoder).

I am no cam-expert , so if oscam and cccam work i dont know. I just use OSCAM-server and it works fine.

PS
Süheyl Karakas wrote:

Sorry, I had to do some shopping, but yes the line is green. The card is active.

We all have to eat ;-)

Süheyl Karakas wrote:

Is it possible that it doesn't descramble because the server is a CCcam server and I'm using Oscam?
I tried to use CCcam but then I couldn't get the line green. That's why I reverted to Oscam.

One more question:

This is very confusing: What do you mean, 'the server is cccam', ' i am using oscam', and 'that's why i reverted to oscam' .
Is the server cccam or oscam ?

Whatever it is, it sounds pretty likely the problem is over there somewhere.....

Hi,

I'm connecting with my Oscam client to a CCcam server. I tried to connect with CCcam client to CCcam server. That was succesfull. I was able to see in the webinterface of CCcam that I was connected to the server. But in Tvheadend I was not able to get the card active (green).
That's why I changed it to Oscam. I'm connected to the server. The server delivers ECM's but somehow Oscam does not use them to descramble.

I've added the oscam log file

Your problem really seems to come from your specific oscam-setup, so maybe better try ask at the oscam forum I guess.

Only thing I know is that OSCAM with a Ziggosmartcard and smartcardreader seems to work pretty well in general.

https://tvheadend.org/projects/tvheadend/wiki/Tvheadend_oscam_ziggo

I didn't had the "Update Card" checked. I'll try it again when I'm at home. Thank you for your efforts :-)

sadly that didn't work... Getting desperate now...

Süheyl Karakas wrote:

sadly that didn't work... Getting desperate now...

Hi,

Well, best would be that you give some more information and that you ask at the oscam-forum since it seems to be an oscam problem.

Also (most of it allready stated earlier):
- look at the camserverlogs ( not client).
- post some information about how your smartcard reader with smartcard is connected to the server.
( next to that, apparently you now checked 'update card', but did you perform the check on the card by using it in a regular ziggo tv tuner? The update card will only work ( i think) on an already activated card).
- Check all parameters as given in the link to the oscam/ziggo howto above ( also , using oscam server seems to work, so maybe try that?).

Hope this helps,

Alex.

Hi,

First of all, I have I regular Ziggo tuner, which I use daily. That same card I'm trying to get running on my HTPC.

I've tried something different yesterday to check it's the server or the client. I took my client to my brother. There I used his oscam config files on my client. His client is running for several months now, so there's. When I tried mine I almost had the same issue. Descrambled channels working fine. Scrambled works a little longer on his server but freezes also after a while. But while working, I had sound. This I did not have at my own server.

In the attachments I have my oscam files. This is what I see in syslog:

Sep 3 13:11:23 mediacenter tvheadend3426: subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" unsubscribing from "Nederland 1"
Sep 3 13:11:23 mediacenter tvheadend3426: subscription: "192.168.1.26 [ xbmc | XBMC Media Center ]" subscribing on "RTL 7", weight: 150, adapter: "DDbridge_01", network: "<N/A>", mux: "610,000 kHz", provider: "Ziggo", service: "RTL 7", quality: 100
Sep 3 13:11:30 mediacenter tvheadend3426: cwc: Can not descramble service "RTL 7", access denied (seqno: 1693 Req delay: 4 ms)

In XBMC I get this error message:

Tvheadend HTSP Client No Access

I've also added some oscam.log files

Hi,

It looks like the CAM-server rejects the requests from oscam.

So, best advice i think is that you need to look at the logfiles of the cam SERVER, since the server rejects your oscam-client.( and you seem to use oscam as a client for a real card server).

NB: The CAM server is the server to which t he actual cardreader and card is connected, ( and the services running on that machine) .
Your oscam-config doesnt contain an actual cardreader as far as i can see, since you use oscam as an intermdediate step (which I would advice you not to do: Just use OSCAM with your reader using the tutorial posted earlier).

Next to this, a bit more information would help: What machine is running what ? Also , you should try using fixed IP addresses for the various services since they seem to be running on seperate machines. (your IP ranges use some dynamic DNS).

Alex.

Hi Alex,

I have a client which is described in my first post. I have fixed its IP in my router as I had problems with fixed IP in Linux. Somehow I had no connection anymore from time to time. I changed that to dynamic on my client and fixed it on my router. That solved the connectivity problem.

I also thought it must be the server. That's why I took my client to my brother, used his oscam files to connect to his server. Same result, so it must be the client.

My setup is as follow. I use my client to connect to my colleague. His server is a Dreambox D8000. Several other colleagues connect the same way to that server, so another sign that it must be the client...

Tonight my colleague will turn on the logging, so I can you send that to. Hopefully we can get some information from that, but I'm not optimistic...
But I found this:
http://www.sat4all.com/forums/topic/330423-tvheadend-oscam-smartreader-no-decoding/
Maybe I should install everything once again...

Hi!
I bad a problem like yours on m'y DS213j before....

In m'y case, Oscam seems to wait and freeze after received about 10 requests....and doesn't answer tvheadend ta time!!!

I solved this with the Oscam shell parameter -r 2 ..... And now, oscam "restart" about each minute, but it's working

Best regards.
JD.

Hi!

My problem was solved by using oscam-svn instead of oscam :-)

Either way, thank you for your information.

The only problem I still didn't solve is the fact that I have little artifacts. Especially on the QAM-256 channels. It's not much, but disturbing. It occurs only with moving objects on screen. When I watch Formula 1 on screen, everything is sharp, except the cars. It leaves some traces.

Don't kow how to fix that...