[ 1110.716494] VIDEO1: total delay: 18000 (200 ms) [ 1110.717056] VIDEO1: enable deinterlacer [ 1110.722010] VIDEO1: start decode [ 1111.047985] audio ch0: tsm fail [ 1111.048016] audio ch0: pts error 0 PTS 0xbca2ba1c, STC 0xbca30fe6, type 0 [ 1111.048032] audio ch0: tsm fail [ 1111.048041] audio ch0: pts error 0 PTS 0xbca2c28c, STC 0xbca30fe8, type 1 [ 1111.059125] VIDEO1: first pts 0xbca43530 [ 1111.059148] VIDEO1: framerate changed 3 [ 1111.059154] VIDEO1: resolution changed (1920 1080) [ 1111.059163] VIDEO1: aspect changed 3 [ 1111.208691] VIDEO1: enable tsm [ 1111.735755] VIDEO1: progressive changed (0) [ 1122.843943] stop PCR0 on dmx 0 pid:cf [ 1122.844156] stop VIDEO0 on dmx 0 pid:cf [ 1122.844960] VIDEO0: stop decode [ 1122.845422] audio ch0: decoder stop [ 1122.865777] stop AUDIO0 on dmx 0 pid:133 [ 1122.865788] audio ch0: xpt stop [ 1122.865798] sync0 1fff no decoder left in sync handling so disable [ 1122.865803] sync0: more pcroffset0 users left [ 1122.870438] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1122.870637] start AUDIO0 on dmx 16 pid:133 index 2 [ 1122.870644] audio ch0: xpt start [ 1122.870665] sync0 is off so we enable it [ 1122.870670] sync0: allocated pcroffset 1 [ 1122.870680] sync0: playback! [ 1122.870839] audio ch0: MPEG [ 1122.870928] audio ch0: decoder start [ 1122.870936] audio ch0 delay 45000 (500 ms) [ 1122.870942] VIDEO0: total delay: 0 (0 ms) [ 1122.872152] start VIDEO0 on dmx 16 pid:cf index 3 [ 1122.872192] VIDEO0: total delay: 45000 (500 ms) [ 1122.877297] VIDEO0: start decode [ 1122.882542] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.882784] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.882909] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.883073] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.883986] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.884239] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.900667] audio ch0: first pts 0xa3d6d39c, stc 0xbcb356c4, pcr valid [ 1122.902151] audio ch0: lock [ 1122.902185] audio ch0: sample rate changed isr (48000) [ 1122.902194] audio ch0: adjusting GA threshold to: 36ms [ 1122.913487] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.913726] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.916752] VIDEO0 use saved pts 0xbcb2edf0 (ret 0) [ 1122.976021] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1122.976197] VIDEO0: first pts 0xa3d7fa50 [ 1123.141310] VIDEO0: enable tsm [ 1124.698562] audio ch0: decoder stop [ 1124.719070] stop AUDIO0 on dmx 16 pid:133 [ 1124.719082] audio ch0: xpt stop [ 1124.719636] VIDEO0: update_trickmode, sm=0, ff=4 [ 1124.719647] -> fast forward rate 4, mode 1 [ 1124.719869] -> (STC-)trickmode active, TSM [ 1124.725713] -> speedup=4,slowdown=0 [ 1124.756172] VIDEO0: first pts 0xa3de2150 [ 1124.756245] VIDEO0: update_trickmode, sm=0, ff=4 [ 1124.756267] -> fast forward rate 4, mode 1 [ 1124.756273] -> (STC-)trickmode active, TSM [ 1124.756285] -> speedup=4,slowdown=0 [ 1127.845723] VIDEO0: flush video decoder [ 1128.036233] VIDEO0: first pts 0xa58c76f0 [ 1128.036317] VIDEO0: update_trickmode, sm=0, ff=4 [ 1128.036338] -> fast forward rate 4, mode 1 [ 1128.036344] -> (STC-)trickmode active, TSM [ 1128.036356] -> speedup=4,slowdown=0 [ 1129.647834] VIDEO0: update_trickmode, sm=0, ff=8 [ 1129.647848] -> fast forward rate 8, mode 0 [ 1129.653715] -> (STC-)trickmode active, TSM [ 1129.653727] -> speedup=8,slowdown=0 [ 1129.676264] VIDEO0: first pts 0xa59eebf0 [ 1129.676346] VIDEO0: update_trickmode, sm=0, ff=8 [ 1129.676366] -> fast forward rate 8, mode 0 [ 1129.676372] -> (STC-)trickmode active, TSM [ 1129.676382] -> speedup=8,slowdown=0 [ 1135.636363] pts_error_isr: 14 callbacks suppressed [ 1135.636390] VIDEO0: pts error PTS 0x52ef4428, STC 0x52f01bba, type 0 [ 1136.956389] VIDEO0: pts error PTS 0x52f58748, STC 0x52f68456, type 0 [ 1138.456412] VIDEO0: pts error PTS 0x52fc7328, STC 0x52fdc496, type 0 [ 1139.216423] VIDEO0: pts error PTS 0x52ffa2c8, STC 0x53009fd7, type 0 [ 1139.536012] VIDEO0: flush video decoder [ 1139.543776] start AUDIO0 on dmx 16 pid:133 index 2 [ 1139.543787] audio ch0: xpt start [ 1139.543964] audio ch0: MPEG [ 1139.544031] audio ch0: decoder start [ 1139.544042] audio ch0 delay 45000 (500 ms) [ 1139.544047] VIDEO0: total delay: 45000 (500 ms) [ 1139.544750] VIDEO0: update_trickmode, sm=0, ff=0 [ 1139.544760] -> fast forward rate 0, mode 2 [ 1139.550401] -> no trickmode active, TSM [ 1139.551133] audio ch0: first pts 0xa600245c, stc 0xbcca3454, pcr valid [ 1139.555061] audio ch0: lock [ 1139.555088] audio ch0: sample rate changed isr (48000) [ 1139.555890] -> speedup=1,slowdown=0 [ 1139.656462] VIDEO0: first pts 0xa601b050 [ 1139.807222] VIDEO0: enable tsm [ 1140.129516] audio ch0: status ready isr [ 1142.395493] audio ch0: decoder stop [ 1142.416561] stop AUDIO0 on dmx 16 pid:133 [ 1142.416572] audio ch0: xpt stop [ 1142.419087] VIDEO0: update_trickmode, sm=0, ff=4 [ 1142.419096] -> fast forward rate 4, mode 1 [ 1142.419315] -> (STC-)trickmode active, TSM [ 1142.425215] -> speedup=4,slowdown=0 [ 1142.456514] VIDEO0: first pts 0xa6099950 [ 1142.456672] VIDEO0: update_trickmode, sm=0, ff=4 [ 1142.456682] -> fast forward rate 4, mode 1 [ 1142.456690] -> (STC-)trickmode active, TSM [ 1142.456700] -> speedup=4,slowdown=0 [ 1144.354157] VIDEO0: flush video decoder [ 1144.360994] start AUDIO0 on dmx 16 pid:133 index 2 [ 1144.361004] audio ch0: xpt start [ 1144.361167] audio ch0: MPEG [ 1144.361232] audio ch0: decoder start [ 1144.361242] audio ch0 delay 45000 (500 ms) [ 1144.361247] VIDEO0: total delay: 45000 (500 ms) [ 1144.362078] VIDEO0: update_trickmode, sm=0, ff=0 [ 1144.362088] -> fast forward rate 0, mode 2 [ 1144.367693] -> no trickmode active, TSM [ 1144.368413] audio ch0: first pts 0xa612bb1c, stc 0xbcd0d1de, pcr valid [ 1144.370368] audio ch0: lock [ 1144.370395] audio ch0: sample rate changed isr (48000) [ 1144.373220] -> speedup=1,slowdown=0 [ 1144.476571] VIDEO0: first pts 0xa6145d90 [ 1144.626485] VIDEO0: enable tsm [ 1144.965628] audio ch0: status ready isr [ 1179.719577] audio ch0: flush audio decode [ 1179.740152] audio ch0 delay 45000 (500 ms) [ 1179.740160] VIDEO0: total delay: 45000 (500 ms) [ 1179.740453] VIDEO0: flush video decoder [ 1179.753109] VIDEO0 use saved pts 0xa6428210 (ret 0) [ 1179.753603] VIDEO0 use saved pts 0xa6428210 (ret 0) [ 1179.753812] VIDEO0 use saved pts 0xa6428210 (ret 0) [ 1179.754452] VIDEO0 use saved pts 0xa6428210 (ret 0) [ 1179.759493] audio ch0: first pts 0xa78c0b9c, stc 0xbd016bce, pcr valid [ 1179.762283] audio ch0: lock [ 1179.762311] audio ch0: sample rate changed isr (48000) [ 1179.857335] VIDEO0: first pts 0xa78d6a90 [ 1180.007303] VIDEO0: enable tsm [ 1180.371192] audio ch0: status ready isr [ 1182.131720] audio ch0: flush audio decode [ 1182.152313] audio ch0 delay 45000 (500 ms) [ 1182.152323] VIDEO0: total delay: 45000 (500 ms) [ 1182.152638] VIDEO0: flush video decoder [ 1182.159209] audio ch0: use saved pts 0xa78e738c (0x0) (ret 1) [ 1182.159233] audio ch0: decoder stop [ 1182.162886] VIDEO0 use saved pts 0xa78e83d0 (ret 0) [ 1182.162988] stop AUDIO0 on dmx 16 pid:133 [ 1182.162994] audio ch0: xpt stop [ 1182.163445] VIDEO0: update_trickmode, sm=0, ff=1 [ 1182.163460] -> fast forward rate 1, mode 0 [ 1182.163690] -> trickmode active, VSYNC [ 1182.169384] -> speedup=1,slowdown=0 [ 1182.172842] VIDEO0 use saved pts 0xa78e83d0 (ret 0) [ 1182.173283] VIDEO0 use saved pts 0xa78e83d0 (ret 0) [ 1182.175027] VIDEO0 use saved pts 0xa78e83d0 (ret 0) [ 1182.537287] VIDEO0: first pts 0xa78ef450 [ 1182.537494] VIDEO0: update_trickmode, sm=0, ff=1 [ 1182.537509] -> fast forward rate 1, mode 0 [ 1182.537520] -> trickmode active, VSYNC [ 1182.537532] -> speedup=1,slowdown=0 [ 1183.376645] VIDEO0: flush video decoder [ 1183.657315] VIDEO0: first pts 0xa781fd90 [ 1183.657669] VIDEO0: update_trickmode, sm=0, ff=1 [ 1183.657683] -> fast forward rate 1, mode 0 [ 1183.657691] -> trickmode active, VSYNC [ 1183.657701] -> speedup=1,slowdown=0 [ 1188.683272] VIDEO0: flush video decoder [ 1188.694801] bcm_mpeg_decoder_get_pts: 1 callbacks suppressed [ 1188.694813] VIDEO0 use saved pts 0xa7033550 (ret 0) [ 1188.695212] start AUDIO0 on dmx 16 pid:133 index 2 [ 1188.695218] audio ch0: xpt start [ 1188.695362] audio ch0: MPEG [ 1188.695420] audio ch0: decoder start [ 1188.695428] audio ch0 delay 45000 (500 ms) [ 1188.695433] VIDEO0: total delay: 45000 (500 ms) [ 1188.696812] VIDEO0: update_trickmode, sm=0, ff=0 [ 1188.696822] -> fast forward rate 0, mode 2 [ 1188.702397] -> no trickmode active, TSM [ 1188.707966] -> speedup=1,slowdown=0 [ 1188.711673] VIDEO0 use saved pts 0xa7033550 (ret 0) [ 1188.711992] VIDEO0 use saved pts 0xa7033550 (ret 0) [ 1188.712428] VIDEO0 use saved pts 0xa7033550 (ret 0) [ 1188.712590] VIDEO0 use saved pts 0xa7033550 (ret 0) [ 1188.713579] audio ch0: first pts 0xa703331c, stc 0xbd0db7aa, pcr valid [ 1188.714505] VIDEO0 use saved pts 0xa7033550 (ret 0) [ 1188.716564] audio ch0: lock [ 1188.716592] audio ch0: sample rate changed isr (48000) [ 1188.837438] VIDEO0: first pts 0xa704f750 [ 1188.986754] VIDEO0: enable tsm [ 1189.325195] audio ch0: status ready isr [ 1190.622308] audio ch0: flush audio decode [ 1190.642910] audio ch0 delay 45000 (500 ms) [ 1190.642919] VIDEO0: total delay: 45000 (500 ms) [ 1190.643175] VIDEO0: flush video decoder [ 1190.650129] audio ch0: use saved pts 0xa704fabc (0x0) (ret 1) [ 1190.650151] audio ch0: decoder stop [ 1190.650677] VIDEO0 use saved pts 0xa7053da0 (ret 0) [ 1190.655014] stop AUDIO0 on dmx 16 pid:133 [ 1190.655022] audio ch0: xpt stop [ 1190.655247] VIDEO0: update_trickmode, sm=0, ff=1 [ 1190.655256] -> fast forward rate 1, mode 0 [ 1190.655473] -> trickmode active, VSYNC [ 1190.661282] -> speedup=1,slowdown=0 [ 1190.711516] VIDEO0 use saved pts 0xa7053da0 (ret 0) [ 1190.897449] VIDEO0: first pts 0xa704f750 [ 1190.897533] VIDEO0: update_trickmode, sm=0, ff=1 [ 1190.897543] -> fast forward rate 1, mode 0 [ 1190.897549] -> trickmode active, VSYNC [ 1190.897568] -> speedup=1,slowdown=0 [ 1192.207287] VIDEO0: flush video decoder [ 1192.213614] start AUDIO0 on dmx 16 pid:133 index 2 [ 1192.213624] audio ch0: xpt start [ 1192.213784] audio ch0: MPEG [ 1192.213907] audio ch0: decoder start [ 1192.213922] audio ch0 delay 45000 (500 ms) [ 1192.213927] VIDEO0: total delay: 45000 (500 ms) [ 1192.214569] VIDEO0: update_trickmode, sm=0, ff=0 [ 1192.214579] -> fast forward rate 0, mode 2 [ 1192.220224] -> no trickmode active, TSM [ 1192.225701] -> speedup=1,slowdown=0 [ 1192.240250] audio ch0: first pts 0xa6eae03c, stc 0xbd128f7c, pcr valid [ 1192.241378] audio ch0: lock [ 1192.377499] VIDEO0: first pts 0xa6edacd0 [ 1192.528988] VIDEO0: enable tsm [ 1404.751916] stop VIDEO0 on dmx 16 pid:cf [ 1404.752859] VIDEO0: stop decode [ 1404.753430] audio ch0: decoder stop [ 1404.774013] stop AUDIO0 on dmx 16 pid:133 [ 1404.774021] audio ch0: xpt stop [ 1404.774028] sync0 1fff no decoder left in sync handling so disable [ 1404.774032] sync0: was last pcroffset1 user so free it [ 1404.790198] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1404.790578] start PCR0 on dmx 0 pid:cf index 13 [ 1404.792579] start AUDIO0 on dmx 0 pid:133 index 14 [ 1404.792592] audio ch0: xpt start [ 1404.792603] audio ch0: reset GA threshold to 8ms [ 1404.792624] sync0 is off so we enable it [ 1404.792634] sync0: use shared pcroffset 0 [ 1404.792650] sync0: demux-index 0 pcr pid cf [ 1404.792664] sync0: using 13 as pcr pidchannel [ 1404.792683] audio ch0: MPEG [ 1404.793096] audio ch0: decoder start [ 1404.793109] audio ch0 delay 18000 (200 ms) [ 1404.793117] VIDEO0: total delay: 18000 (200 ms) [ 1404.794223] start VIDEO0 on dmx 0 pid:cf index 13 [ 1404.794261] VIDEO0: total delay: 18000 (200 ms) [ 1404.799396] VIDEO0: start decode [ 1404.812269] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1404.915147] audio ch0: first pts 0xbe36bcfc, stc 0xbe369e96, pcr valid [ 1404.922213] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1404.937891] audio ch0: lock [ 1404.937923] bcm_audio_decoder_sample_rate_change_isr: 7 callbacks suppressed [ 1404.937928] audio ch0: sample rate changed isr (48000) [ 1405.246428] audio ch0: status ready isr [ 1405.721662] VIDEO0: first pts 0xbe382cd0 [ 1405.872020] VIDEO0: enable tsm [ 1409.009151] stop PCR1 on dmx 0 pid:cf [ 1409.009348] stop VIDEO1 on dmx 0 pid:cf [ 1409.011028] VIDEO1: stop decode [ 1409.011042] sync1 1fff no decoder left in sync handling so disable [ 1409.011047] sync1: more pcroffset0 users left [ 1409.012769] VIDEO1: set blank to 1 [ 1409.511097] VIDEO1: mute pip delayed ch state 0 [ 1724.528705] stop PCR0 on dmx 0 pid:cf [ 1724.528872] stop VIDEO0 on dmx 0 pid:cf [ 1724.530662] VIDEO0: stop decode [ 1724.531110] audio ch0: decoder stop [ 1724.551498] stop AUDIO0 on dmx 0 pid:133 [ 1724.551510] audio ch0: xpt stop [ 1724.551520] sync0 1fff no decoder left in sync handling so disable [ 1724.551526] sync0: was last pcroffset0 user so free it [ 1724.552335] demux16 is already set to DVR source!! [ 1724.554289] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1724.554590] start AUDIO0 on dmx 16 pid:133 index 3 [ 1724.554628] audio ch0: xpt start [ 1724.554650] sync0 is off so we enable it [ 1724.554660] sync0: allocated pcroffset 0 [ 1724.554669] sync0: playback! [ 1724.554841] audio ch0: MPEG [ 1724.554910] audio ch0: decoder start [ 1724.554917] audio ch0 delay 45000 (500 ms) [ 1724.554923] VIDEO0: total delay: 0 (0 ms) [ 1724.555849] start VIDEO0 on dmx 16 pid:cf index 6 [ 1724.555888] VIDEO0: total delay: 45000 (500 ms) [ 1724.560739] VIDEO0: start decode [ 1724.568803] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.568990] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.572038] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.573101] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.573471] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.598454] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.604651] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.605036] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.609871] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.610878] VIDEO0 use saved pts 0xbfed3610 (ret 0) [ 1724.843241] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 1725.167470] VIDEO0: first pts 0xbfeef810 [ 1725.288430] VIDEO0: apply delayed pause! [ 1725.332624] VIDEO0: enable tsm [ 1733.299946] audio ch0: lock [ 1733.299976] audio ch0: sample rate changed isr (48000) [ 1733.299984] audio ch0: adjusting GA threshold to: 36ms [ 1733.300019] audio ch0: first pts 0xbfed8ddc, stc 0xbff9f432, pcr invalid [ 1733.300074] audio ch0: tsm fail [ 1733.300084] audio ch0: pts error 0 PTS 0xbfed8ddc, STC 0xbfed766a, type 0 [ 1733.300094] audio ch0: tsm fail [ 1733.300104] audio ch0: pts error 0 PTS 0xbfed964c, STC 0xbfed766a, type 1 [ 1733.300111] audio ch0: tsm fail [ 1733.300121] audio ch0: pts error 0 PTS 0xbfed9ebc, STC 0xbfed766c, type 1 [ 1733.300127] audio ch0: tsm fail [ 1733.300134] audio ch0: pts error 0 PTS 0xbfeda72c, STC 0xbfed766e, type 1 [ 1734.007694] audio ch0: status ready isr [ 1937.711246] VIDEO0: pts error PTS 0x605415e4, STC 0x60831636, type 0 [ 2116.470790] audio ch0: decoder stop [ 2116.491151] stop AUDIO0 on dmx 16 pid:133 [ 2116.491160] audio ch0: xpt stop [ 2116.491361] VIDEO0: update_trickmode, sm=0, ff=4 [ 2116.491368] -> fast forward rate 4, mode 1 [ 2116.491613] -> (STC-)trickmode active, TSM [ 2116.497644] -> speedup=4,slowdown=0 [ 2116.501371] VIDEO0 use saved pts 0xc19bb5e0 (ret 0) [ 2116.501888] VIDEO0 use saved pts 0xc19bb5e0 (ret 0) [ 2116.503861] VIDEO0 use saved pts 0xc19bb5e0 (ret 0) [ 2116.505144] VIDEO0 use saved pts 0xc19bb5e0 (ret 0) [ 2116.505824] VIDEO0 use saved pts 0xc19bb5e0 (ret 0) [ 2116.534492] VIDEO0: first pts 0xc1a471d0 [ 2116.534656] VIDEO0: update_trickmode, sm=0, ff=4 [ 2116.534664] -> fast forward rate 4, mode 1 [ 2116.534674] -> (STC-)trickmode active, TSM [ 2116.534684] -> speedup=4,slowdown=0 [ 2120.983642] VIDEO0: update_trickmode, sm=0, ff=8 [ 2120.983658] -> fast forward rate 8, mode 0 [ 2120.989526] -> (STC-)trickmode active, TSM [ 2120.989536] -> speedup=8,slowdown=0 [ 2121.014573] VIDEO0: first pts 0xc1c61010 [ 2121.014755] VIDEO0: update_trickmode, sm=0, ff=8 [ 2121.014770] -> fast forward rate 8, mode 0 [ 2121.014777] -> (STC-)trickmode active, TSM [ 2121.014788] -> speedup=8,slowdown=0 [ 2124.605063] VIDEO0: flush video decoder [ 2124.616985] VIDEO0: update_trickmode, sm=0, ff=1 [ 2124.616998] -> fast forward rate 1, mode 0 [ 2124.617002] -> trickmode active, VSYNC [ 2124.617008] -> speedup=1,slowdown=0 [ 2124.619836] VIDEO0 use saved pts 0xc1ec1350 (ret 0) [ 2124.620939] VIDEO0 use saved pts 0xc1ec1350 (ret 0) [ 2124.623090] VIDEO0 use saved pts 0xc1ec1350 (ret 0) [ 2124.874629] VIDEO0: first pts 0xc1f35390 [ 2124.874804] VIDEO0: update_trickmode, sm=0, ff=1 [ 2124.874818] -> fast forward rate 1, mode 0 [ 2124.874825] -> trickmode active, VSYNC [ 2124.874835] -> speedup=1,slowdown=0 [ 2125.316737] stop VIDEO0 on dmx 16 pid:cf [ 2125.317432] VIDEO0: stop decode [ 2125.317444] sync0 1fff no decoder left in sync handling so disable [ 2125.317451] sync0: was last pcroffset0 user so free it [ 2125.318609] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2125.318762] start PCR0 on dmx 0 pid:cf index 13 [ 2125.319000] start AUDIO0 on dmx 0 pid:133 index 14 [ 2125.319006] audio ch0: xpt start [ 2125.319014] audio ch0: reset GA threshold to 8ms [ 2125.319030] sync0 is off so we enable it [ 2125.319035] sync0: allocated pcroffset 0 [ 2125.319048] sync0: demux-index 0 pcr pid cf [ 2125.319059] sync0: using 13 as pcr pidchannel [ 2125.319077] audio ch0: MPEG [ 2125.319143] audio ch0: decoder start [ 2125.319151] audio ch0 delay 18000 (200 ms) [ 2125.319156] VIDEO0: total delay: 18000 (200 ms) [ 2125.320490] start VIDEO0 on dmx 0 pid:cf index 13 [ 2125.320527] VIDEO0: total delay: 18000 (200 ms) [ 2125.325719] VIDEO0: start decode [ 2125.325980] VIDEO0: update_trickmode, sm=0, ff=0 [ 2125.325985] -> fast forward rate 0, mode 2 [ 2125.325990] -> no trickmode active, TSM [ 2125.325994] -> speedup=1,slowdown=0 [ 2125.360057] audio ch0: first pts 0xc21419dc, stc 0xc213fb76, pcr valid [ 2125.382844] audio ch0: lock [ 2125.382875] audio ch0: sample rate changed isr (48000) [ 2125.691337] audio ch0: status ready isr [ 2125.914637] VIDEO0: first pts 0xc2159a90 [ 2126.065371] VIDEO0: enable tsm [ 2128.459470] start PCR1 on dmx 0 pid:cf index 13 [ 2128.460114] start VIDEO1 on dmx 0 pid:cf index 13 [ 2128.460152] sync1 is off so we enable it [ 2128.460157] sync1: use shared pcroffset 0 [ 2128.460168] sync1: demux-index 0 pcr pid cf [ 2128.460179] sync1: using 13 as pcr pidchannel [ 2128.460188] VIDEO1: total delay: 18000 (200 ms) [ 2128.465450] VIDEO1: start decode [ 2128.810574] audio ch0: tsm fail [ 2128.810589] audio ch0: pts error 0 PTS 0xc21862bc, STC 0xc218b886, type 1 [ 2128.810601] audio ch0: tsm fail [ 2128.810607] audio ch0: pts error 0 PTS 0xc2186b2c, STC 0xc218b888, type 1 [ 2129.254507] VIDEO1: first pts 0xc219ff90 [ 2129.404262] VIDEO1: enable tsm [ 2157.678872] stop PCR0 on dmx 0 pid:cf [ 2157.679040] stop VIDEO0 on dmx 0 pid:cf [ 2157.679809] VIDEO0: stop decode [ 2157.680243] audio ch0: decoder stop [ 2157.700639] stop AUDIO0 on dmx 0 pid:133 [ 2157.700652] audio ch0: xpt stop [ 2157.700663] sync0 1fff no decoder left in sync handling so disable [ 2157.700668] sync0: more pcroffset0 users left [ 2157.703557] demux16 is already set to DVR source!! [ 2157.704628] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2157.705095] start AUDIO0 on dmx 16 pid:133 index 2 [ 2157.705104] audio ch0: xpt start [ 2157.705172] sync0 is off so we enable it [ 2157.705179] sync0: allocated pcroffset 1 [ 2157.705188] sync0: playback! [ 2157.705447] audio ch0: MPEG [ 2157.705746] audio ch0: decoder start [ 2157.705754] audio ch0 delay 45000 (500 ms) [ 2157.705759] VIDEO0: total delay: 0 (0 ms) [ 2157.706908] start VIDEO0 on dmx 16 pid:cf index 3 [ 2157.706952] VIDEO0: total delay: 45000 (500 ms) [ 2157.711981] VIDEO0: start decode [ 2157.717311] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.717607] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.717734] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.717917] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.718758] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.719022] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.720128] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.720816] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.736469] audio ch0: first pts 0xa3d6d39c, stc 0xc240719c, pcr valid [ 2157.737820] audio ch0: lock [ 2157.737857] audio ch0: sample rate changed isr (48000) [ 2157.737867] audio ch0: adjusting GA threshold to: 36ms [ 2157.750903] VIDEO0 use saved pts 0xc24010e0 (ret 0) [ 2157.795170] VIDEO0: first pts 0xa3d7fa50 [ 2157.834742] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2157.944498] VIDEO0: enable tsm [ 2158.500816] audio ch0: flush audio decode [ 2158.521381] audio ch0 delay 45000 (500 ms) [ 2158.521391] VIDEO0: total delay: 45000 (500 ms) [ 2158.523167] VIDEO0: flush video decoder [ 2158.573012] audio ch0: first pts 0xa80dc59c, stc 0xc24197b2, pcr valid [ 2158.574961] audio ch0: lock [ 2158.715187] VIDEO0: first pts 0xa80fef10 [ 2158.864489] VIDEO0: enable tsm [ 2339.191090] audio ch0: flush audio decode [ 2339.211722] audio ch0 delay 45000 (500 ms) [ 2339.211732] VIDEO0: total delay: 45000 (500 ms) [ 2339.211969] VIDEO0: flush video decoder [ 2339.220190] bcm_mpeg_decoder_get_pts: 15 callbacks suppressed [ 2339.220203] VIDEO0 use saved pts 0xa90509f0 (ret 0) [ 2339.220697] VIDEO0 use saved pts 0xa90509f0 (ret 0) [ 2339.220910] VIDEO0 use saved pts 0xa90509f0 (ret 0) [ 2339.223510] VIDEO0 use saved pts 0xa90509f0 (ret 0) [ 2339.235501] audio ch0: first pts 0xaa4dd43c, stc 0xc339b0bc, pcr valid [ 2339.236653] audio ch0: lock [ 2339.236686] audio ch0: sample rate changed isr (48000) [ 2339.338375] VIDEO0: first pts 0xaa5046d0 [ 2339.495368] VIDEO0: enable tsm [ 2339.847173] audio ch0: status ready isr [ 2341.384397] audio ch0: decoder stop [ 2341.404952] stop AUDIO0 on dmx 16 pid:133 [ 2341.404964] audio ch0: xpt stop [ 2341.405517] VIDEO0: update_trickmode, sm=0, ff=4 [ 2341.405527] -> fast forward rate 4, mode 1 [ 2341.405748] -> (STC-)trickmode active, TSM [ 2341.411590] -> speedup=4,slowdown=0 [ 2341.438388] VIDEO0: first pts 0xaa566dd0 [ 2341.438646] VIDEO0: update_trickmode, sm=0, ff=4 [ 2341.438662] -> fast forward rate 4, mode 1 [ 2341.438670] -> (STC-)trickmode active, TSM [ 2341.438681] -> speedup=4,slowdown=0 [ 2342.983029] VIDEO0: update_trickmode, sm=0, ff=8 [ 2342.983044] -> fast forward rate 8, mode 0 [ 2342.992844] -> (STC-)trickmode active, TSM [ 2342.992858] -> speedup=8,slowdown=0 [ 2343.018426] VIDEO0: first pts 0xaa68e2d0 [ 2343.018569] VIDEO0: update_trickmode, sm=0, ff=8 [ 2343.018581] -> fast forward rate 8, mode 0 [ 2343.018588] -> (STC-)trickmode active, TSM [ 2343.018601] -> speedup=8,slowdown=0 [ 2345.513622] VIDEO0: flush video decoder [ 2345.520828] start AUDIO0 on dmx 16 pid:133 index 2 [ 2345.520838] audio ch0: xpt start [ 2345.521010] audio ch0: MPEG [ 2345.521085] audio ch0: decoder start [ 2345.521098] audio ch0 delay 45000 (500 ms) [ 2345.521103] VIDEO0: total delay: 45000 (500 ms) [ 2345.521845] VIDEO0: update_trickmode, sm=0, ff=0 [ 2345.521855] -> fast forward rate 0, mode 2 [ 2345.527455] -> no trickmode active, TSM [ 2345.528156] audio ch0: first pts 0xaa836b9c, stc 0xc34254f6, pcr valid [ 2345.529468] audio ch0: lock [ 2345.529500] audio ch0: sample rate changed isr (48000) [ 2345.532990] -> speedup=1,slowdown=0 [ 2345.618490] VIDEO0: first pts 0xaa857350 [ 2345.775410] VIDEO0: enable tsm [ 2346.105918] audio ch0: status ready isr [ 2346.608772] audio ch0: decoder stop [ 2346.629167] stop AUDIO0 on dmx 16 pid:133 [ 2346.629179] audio ch0: xpt stop [ 2346.629766] VIDEO0: update_trickmode, sm=0, ff=4 [ 2346.629777] -> fast forward rate 4, mode 1 [ 2346.630015] -> (STC-)trickmode active, TSM [ 2346.635858] -> speedup=4,slowdown=0 [ 2346.658492] VIDEO0: first pts 0xaa8b6210 [ 2346.658782] VIDEO0: update_trickmode, sm=0, ff=4 [ 2346.658797] -> fast forward rate 4, mode 1 [ 2346.658804] -> (STC-)trickmode active, TSM [ 2346.658818] -> speedup=4,slowdown=0 [ 2357.234057] VIDEO0: flush video decoder [ 2357.242857] start AUDIO0 on dmx 16 pid:133 index 2 [ 2357.242867] audio ch0: xpt start [ 2357.243618] audio ch0: MPEG [ 2357.244257] audio ch0: decoder start [ 2357.244268] audio ch0 delay 45000 (500 ms) [ 2357.244274] VIDEO0: total delay: 45000 (500 ms) [ 2357.246024] VIDEO0: update_trickmode, sm=0, ff=0 [ 2357.246036] -> fast forward rate 0, mode 2 [ 2357.251738] -> no trickmode active, TSM [ 2357.253612] audio ch0: first pts 0xaac4addc, stc 0xc3526f1e, pcr valid [ 2357.254915] audio ch0: lock [ 2357.254942] audio ch0: sample rate changed isr (48000) [ 2357.257279] -> speedup=1,slowdown=0 [ 2357.378690] VIDEO0: first pts 0xaac72610 [ 2357.534301] VIDEO0: enable tsm [ 2357.854206] audio ch0: status ready isr [ 2373.852763] stop VIDEO0 on dmx 16 pid:cf [ 2373.853689] VIDEO0: stop decode [ 2373.854220] audio ch0: decoder stop [ 2373.874653] stop AUDIO0 on dmx 16 pid:133 [ 2373.874663] audio ch0: xpt stop [ 2373.874670] sync0 1fff no decoder left in sync handling so disable [ 2373.874676] sync0: was last pcroffset1 user so free it [ 2373.889533] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2373.889915] start PCR0 on dmx 0 pid:cf index 13 [ 2373.890464] start AUDIO0 on dmx 0 pid:133 index 14 [ 2373.890472] audio ch0: xpt start [ 2373.890480] audio ch0: reset GA threshold to 8ms [ 2373.890497] sync0 is off so we enable it [ 2373.890502] sync0: use shared pcroffset 0 [ 2373.890514] sync0: demux-index 0 pcr pid cf [ 2373.890528] sync0: using 13 as pcr pidchannel [ 2373.890541] audio ch0: MPEG [ 2373.890771] audio ch0: decoder start [ 2373.890778] audio ch0 delay 18000 (200 ms) [ 2373.890784] VIDEO0: total delay: 18000 (200 ms) [ 2373.891869] start VIDEO0 on dmx 0 pid:cf index 13 [ 2373.891905] VIDEO0: total delay: 18000 (200 ms) [ 2373.897102] VIDEO0: start decode [ 2373.908415] audio ch0: first pts 0xc3696c9c, stc 0xc349220e, pcr invalid [ 2373.910239] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2373.931162] audio ch0: lock [ 2373.931193] audio ch0: sample rate changed isr (48000) [ 2373.931207] audio ch0: tsm fail [ 2373.931214] audio ch0: pts error 0 PTS 0xc3696c9c, STC 0xc3492a12, type 0 [ 2373.953928] audio ch0: tsm fail [ 2373.953939] audio ch0: pts error 0 PTS 0xc369750c, STC 0xc3695e38, type 1 [ 2374.048563] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2374.287768] audio ch0: status ready isr [ 2374.618975] VIDEO0: first pts 0xc36b4750 [ 2374.784013] VIDEO0: enable tsm [ 2379.117633] stop PCR1 on dmx 0 pid:cf [ 2379.117807] stop VIDEO1 on dmx 0 pid:cf [ 2379.122276] VIDEO1: stop decode [ 2379.122291] sync1 1fff no decoder left in sync handling so disable [ 2379.122295] sync1: more pcroffset0 users left [ 2379.622971] VIDEO1: mute pip delayed ch state 0 [ 2930.616952] start PCR1 on dmx 0 pid:cf index 13 [ 2930.618045] start VIDEO1 on dmx 0 pid:cf index 13 [ 2930.618082] sync1 is off so we enable it [ 2930.618089] sync1: use shared pcroffset 0 [ 2930.618101] sync1: demux-index 0 pcr pid cf [ 2930.618111] sync1: using 13 as pcr pidchannel [ 2930.618124] VIDEO1: total delay: 18000 (200 ms) [ 2930.623356] VIDEO1: start decode [ 2930.952629] audio ch0: tsm fail [ 2930.952658] audio ch0: pts error 0 PTS 0xc665f0dc, STC 0xc66646a4, type 0 [ 2930.952675] audio ch0: tsm fail [ 2930.952684] audio ch0: pts error 0 PTS 0xc665f94c, STC 0xc66646a6, type 1 [ 2930.952695] audio ch0: tsm fail [ 2930.952703] audio ch0: pts error 0 PTS 0xc66601bc, STC 0xc66646a8, type 1 [ 2931.248560] VIDEO1: first pts 0xc6677cd0 [ 2931.407170] VIDEO1: enable tsm [ 2938.378171] stop PCR0 on dmx 0 pid:cf [ 2938.378341] stop VIDEO0 on dmx 0 pid:cf [ 2938.380412] VIDEO0: stop decode [ 2938.380799] audio ch0: decoder stop [ 2938.401287] stop AUDIO0 on dmx 0 pid:133 [ 2938.401299] audio ch0: xpt stop [ 2938.401309] sync0 1fff no decoder left in sync handling so disable [ 2938.401314] sync0: more pcroffset0 users left [ 2938.403477] demux16 is already set to DVR source!! [ 2938.404441] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2938.404876] start AUDIO0 on dmx 16 pid:133 index 2 [ 2938.404884] audio ch0: xpt start [ 2938.404906] sync0 is off so we enable it [ 2938.404914] sync0: allocated pcroffset 1 [ 2938.404924] sync0: playback! [ 2938.405233] audio ch0: MPEG [ 2938.405445] audio ch0: decoder start [ 2938.405454] audio ch0 delay 45000 (500 ms) [ 2938.405462] VIDEO0: total delay: 0 (0 ms) [ 2938.406614] start VIDEO0 on dmx 16 pid:cf index 3 [ 2938.406655] VIDEO0: total delay: 45000 (500 ms) [ 2938.411722] VIDEO0: start decode [ 2938.416969] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.417263] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.417399] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.417582] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.418444] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.418720] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.419898] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.420628] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.436389] audio ch0: first pts 0xa3d6d39c, stc 0xc6708d98, pcr valid [ 2938.437753] audio ch0: lock [ 2938.437783] audio ch0: sample rate changed isr (48000) [ 2938.437792] audio ch0: adjusting GA threshold to: 36ms [ 2938.450482] VIDEO0 use saved pts 0xc6702ab0 (ret 0) [ 2938.508929] VIDEO0: first pts 0xa3d7fa50 [ 2938.574318] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 2938.658060] VIDEO0: enable tsm [ 2939.214317] audio ch0: flush audio decode [ 2939.234900] audio ch0 delay 45000 (500 ms) [ 2939.234911] VIDEO0: total delay: 45000 (500 ms) [ 2939.235278] VIDEO0: flush video decoder [ 2939.298486] audio ch0: first pts 0xaadaa13c, stc 0xc671bcaa, pcr valid [ 2939.300434] audio ch0: lock [ 2939.448889] VIDEO0: first pts 0xaadd1f10 [ 2939.598079] VIDEO0: enable tsm [ 3226.993168] stop VIDEO0 on dmx 16 pid:cf [ 3226.994525] VIDEO0: stop decode [ 3226.995088] audio ch0: decoder stop [ 3227.015583] stop AUDIO0 on dmx 16 pid:133 [ 3227.015593] audio ch0: xpt stop [ 3227.015601] sync0 1fff no decoder left in sync handling so disable [ 3227.015605] sync0: was last pcroffset1 user so free it [ 3227.028007] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 3227.028502] start PCR0 on dmx 0 pid:cf index 13 [ 3227.028928] start AUDIO0 on dmx 0 pid:133 index 14 [ 3227.028936] audio ch0: xpt start [ 3227.028944] audio ch0: reset GA threshold to 8ms [ 3227.028960] sync0 is off so we enable it [ 3227.028964] sync0: use shared pcroffset 0 [ 3227.028978] sync0: demux-index 0 pcr pid cf [ 3227.028993] sync0: using 13 as pcr pidchannel [ 3227.029008] audio ch0: MPEG [ 3227.029422] audio ch0: decoder start [ 3227.029429] audio ch0 delay 18000 (200 ms) [ 3227.029435] VIDEO0: total delay: 18000 (200 ms) [ 3227.030564] start VIDEO0 on dmx 0 pid:cf index 13 [ 3227.030601] VIDEO0: total delay: 18000 (200 ms) [ 3227.035768] VIDEO0: start decode [ 3227.050282] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 3227.123384] audio ch0: first pts 0xc7fd1dbc, stc 0xc7fcff52, pcr valid [ 3227.138434] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 3227.146238] audio ch0: lock [ 3227.146272] bcm_audio_decoder_sample_rate_change_isr: 17 callbacks suppressed [ 3227.146280] audio ch0: sample rate changed isr (48000) [ 3227.353940] VIDEO0: first pts 0xc7fe6bd0 [ 3227.454804] audio ch0: status ready isr [ 3227.503201] VIDEO0: enable tsm [ 3231.159261] stop PCR1 on dmx 0 pid:cf [ 3231.160272] stop VIDEO1 on dmx 0 pid:cf [ 3231.161015] VIDEO1: stop decode [ 3231.161030] sync1 1fff no decoder left in sync handling so disable [ 3231.161034] sync1: more pcroffset0 users left [ 3231.661612] VIDEO1: mute pip delayed ch state 0 [ 3983.461098] start PCR1 on dmx 0 pid:cf index 13 [ 3983.461780] start VIDEO1 on dmx 0 pid:cf index 13 [ 3983.461849] sync1 is off so we enable it [ 3983.461859] sync1: use shared pcroffset 0 [ 3983.461875] sync1: demux-index 0 pcr pid cf [ 3983.461892] sync1: using 13 as pcr pidchannel [ 3983.461907] VIDEO1: total delay: 18000 (200 ms) [ 3983.468771] VIDEO1: start decode [ 3983.803298] audio ch0: tsm fail [ 3983.803327] audio ch0: pts error 0 PTS 0xcc0bc91c, STC 0xcc0c1ee6, type 1 [ 3983.803344] audio ch0: tsm fail [ 3983.803353] audio ch0: pts error 0 PTS 0xcc0bd18c, STC 0xcc0c1ee8, type 1 [ 3983.803364] audio ch0: tsm fail [ 3983.803371] audio ch0: pts error 0 PTS 0xcc0bd9fc, STC 0xcc0c1eea, type 1 [ 3984.107124] VIDEO1: first pts 0xcc0dba50 [ 3984.256832] VIDEO1: enable tsm [ 3990.938206] stop PCR0 on dmx 0 pid:cf [ 3990.938419] stop VIDEO0 on dmx 0 pid:cf [ 3990.939263] VIDEO0: stop decode [ 3990.940068] audio ch0: decoder stop [ 3990.959857] stop AUDIO0 on dmx 0 pid:133 [ 3990.959869] audio ch0: xpt stop [ 3990.959878] sync0 1fff no decoder left in sync handling so disable [ 3990.959884] sync0: more pcroffset0 users left [ 3990.962094] demux16 is already set to DVR source!! [ 3990.963023] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 3990.963480] start AUDIO0 on dmx 16 pid:133 index 2 [ 3990.963486] audio ch0: xpt start [ 3990.963508] sync0 is off so we enable it [ 3990.963514] sync0: allocated pcroffset 1 [ 3990.963524] sync0: playback! [ 3990.963767] audio ch0: MPEG [ 3990.964008] audio ch0: decoder start [ 3990.964020] audio ch0 delay 45000 (500 ms) [ 3990.964028] VIDEO0: total delay: 0 (0 ms) [ 3990.965143] start VIDEO0 on dmx 16 pid:cf index 3 [ 3990.965181] VIDEO0: total delay: 45000 (500 ms) [ 3990.970251] VIDEO0: start decode [ 3990.975620] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.975879] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.976064] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.976250] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.977140] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.977418] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.978555] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.979248] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3990.994828] audio ch0: first pts 0xa3d6d39c, stc 0xcc15ff1c, pcr valid [ 3990.996195] audio ch0: lock [ 3990.996228] audio ch0: sample rate changed isr (48000) [ 3990.996237] audio ch0: adjusting GA threshold to: 36ms [ 3991.008014] VIDEO0 use saved pts 0xcc159540 (ret 0) [ 3991.067498] VIDEO0: first pts 0xa3d7fa50 [ 3991.118978] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 3991.217023] VIDEO0: enable tsm [ 3991.893708] audio ch0: flush audio decode [ 3991.914044] audio ch0 delay 45000 (500 ms) [ 3991.914053] VIDEO0: total delay: 45000 (500 ms) [ 3991.914326] VIDEO0: flush video decoder [ 3991.982125] audio ch0: first pts 0xac645b9c, stc 0xcc175a34, pcr valid [ 3991.984878] audio ch0: lock [ 3992.107485] VIDEO0: first pts 0xac666e90 [ 3992.257040] VIDEO0: enable tsm [ 4086.550002] stop VIDEO0 on dmx 16 pid:cf [ 4086.550905] VIDEO0: stop decode [ 4086.551479] audio ch0: decoder stop [ 4086.571979] stop AUDIO0 on dmx 16 pid:133 [ 4086.571988] audio ch0: xpt stop [ 4086.571994] sync0 1fff no decoder left in sync handling so disable [ 4086.571998] sync0: was last pcroffset1 user so free it [ 4086.584543] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 4086.584910] start PCR0 on dmx 0 pid:cf index 13 [ 4086.585466] start AUDIO0 on dmx 0 pid:133 index 14 [ 4086.585473] audio ch0: xpt start [ 4086.585481] audio ch0: reset GA threshold to 8ms [ 4086.585496] sync0 is off so we enable it [ 4086.585501] sync0: use shared pcroffset 0 [ 4086.585515] sync0: demux-index 0 pcr pid cf [ 4086.585527] sync0: using 13 as pcr pidchannel [ 4086.585542] audio ch0: MPEG [ 4086.585791] audio ch0: decoder start [ 4086.585799] audio ch0 delay 18000 (200 ms) [ 4086.585805] VIDEO0: total delay: 18000 (200 ms) [ 4086.587018] start VIDEO0 on dmx 0 pid:cf index 13 [ 4086.587055] VIDEO0: total delay: 18000 (200 ms) [ 4086.592218] VIDEO0: start decode [ 4086.607463] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 4086.674717] audio ch0: first pts 0xcc99825c, stc 0xcc9963fa, pcr valid [ 4086.697452] audio ch0: lock [ 4086.697502] bcm_audio_decoder_sample_rate_change_isr: 17 callbacks suppressed [ 4086.697525] audio ch0: sample rate changed isr (48000) [ 4086.702113] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 4087.005971] audio ch0: status ready isr [ 4087.869172] VIDEO0: first pts 0xcc9c1c50 [ 4088.019359] VIDEO0: enable tsm [ 4091.665823] stop PCR1 on dmx 0 pid:cf [ 4091.666010] stop VIDEO1 on dmx 0 pid:cf [ 4091.667354] VIDEO1: stop decode [ 4091.667369] sync1 1fff no decoder left in sync handling so disable [ 4091.667373] sync1: more pcroffset0 users left [ 4092.167461] VIDEO1: mute pip delayed ch state 0 [ 4268.483556] CA: changed index (0 -> 1) of already running pid 00cf on demux 0... this can cause picture/audio drop outs! [ 4268.483915] CA: changed index (0 -> 1) of already running pid 0133 on demux 0... this can cause picture/audio drop outs! [ 5672.577901] stop PCR0 on dmx 0 pid:cf [ 5672.577965] stop VIDEO0 on dmx 0 pid:cf [ 5672.578847] VIDEO0: stop decode [ 5672.579399] audio ch0: decoder stop [ 5672.599884] stop AUDIO0 on dmx 0 pid:133 [ 5672.599895] audio ch0: xpt stop [ 5672.599907] sync0 1fff no decoder left in sync handling so disable [ 5672.599913] sync0: was last pcroffset0 user so free it [ 5672.600872] demux16 is already set to DVR source!! [ 5672.602885] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 5672.603151] start AUDIO0 on dmx 16 pid:133 index 5 [ 5672.603158] audio ch0: xpt start [ 5672.603181] sync0 is off so we enable it [ 5672.603186] sync0: allocated pcroffset 0 [ 5672.603195] sync0: playback! [ 5672.603375] audio ch0: MPEG [ 5672.603444] audio ch0: decoder start [ 5672.603452] audio ch0 delay 45000 (500 ms) [ 5672.603458] VIDEO0: total delay: 0 (0 ms) [ 5672.604356] start VIDEO0 on dmx 16 pid:cf index 6 [ 5672.604441] VIDEO0: total delay: 45000 (500 ms) [ 5672.609341] VIDEO0: start decode [ 5672.616775] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.617013] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.618792] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.619837] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.620286] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.653247] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.654347] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5672.921363] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 5673.619687] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5673.619866] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5673.621179] VIDEO0 use saved pts 0xd51af6d0 (ret 0) [ 5673.717523] VIDEO0: first pts 0xd51d6190 [ 5673.837518] VIDEO0: apply delayed pause! [ 5673.867032] VIDEO0: enable tsm [ 6260.001787] audio ch0: use saved pts 0xd51ae95c (0x197d81600) (ret 1) [ 6260.001810] audio ch0: decoder stop [ 6260.005260] stop AUDIO0 on dmx 16 pid:133 [ 6260.005270] audio ch0: xpt stop [ 6260.005275] audio ch0: unpause [ 6260.005631] VIDEO0: update_trickmode, sm=8, ff=0 [ 6260.005642] -> fast forward rate 0, mode 2 [ 6260.005648] -> (STC-)trickmode active, TSM [ 6260.005657] -> speedup=1,slowdown=8 [ 6260.791994] VIDEO0: update_trickmode, sm=4, ff=0 [ 6260.792010] -> fast forward rate 0, mode 2 [ 6260.792018] -> (STC-)trickmode active, TSM [ 6260.792025] -> speedup=1,slowdown=4 [ 6263.060592] start AUDIO0 on dmx 16 pid:133 index 5 [ 6263.060606] audio ch0: xpt start [ 6263.060807] audio ch0: MPEG [ 6263.061060] audio ch0: decoder start [ 6263.061075] audio ch0 delay 45000 (500 ms) [ 6263.061084] VIDEO0: total delay: 45000 (500 ms) [ 6263.062223] VIDEO0: update_trickmode, sm=0, ff=0 [ 6263.062234] -> fast forward rate 0, mode 2 [ 6263.062239] -> no trickmode active, TSM [ 6263.062245] -> speedup=1,slowdown=0 [ 6263.068071] pts_error_isr: 4 callbacks suppressed [ 6263.068083] VIDEO0: pts error PTS 0x6a8f39e4, STC 0x6c218a20, type 0 [ 6263.670923] audio ch0: first pts 0xd5289f5c, stc 0xd51f47b4, pcr invalid [ 6263.675599] audio ch0: lock [ 6263.675630] audio ch0: sample rate changed isr (48000) [ 6263.675636] audio ch0: adjusting GA threshold to: 36ms [ 6263.688070] VIDEO0: pts error PTS 0x6a8f4b78, STC 0x6a9446f4, type 0 [ 6263.708252] VIDEO0: pts error PTS 0x6a900248, STC 0x6a944a81, type 0 [ 6263.748114] VIDEO0: pts error PTS 0x6a901e68, STC 0x6a945182, type 0 [ 6263.788111] VIDEO0: pts error PTS 0x6a903a88, STC 0x6a94588a, type 0 [ 6263.808094] VIDEO0: pts error PTS 0x6a904898, STC 0x6a945c0d, type 0 [ 6263.828104] VIDEO0: pts error PTS 0x6a9056a8, STC 0x6a945f92, type 0 [ 6263.848098] VIDEO0: pts error PTS 0x6a9064b8, STC 0x6a946315, type 0 [ 6264.282521] audio ch0: status ready isr [ 6268.543839] audio ch0: flush audio decode [ 6268.564445] audio ch0 delay 45000 (500 ms) [ 6268.564454] VIDEO0: total delay: 45000 (500 ms) [ 6268.564720] VIDEO0: flush video decoder [ 6268.692109] audio ch0: first pts 0xd671af5c, stc 0xd5297fa6, pcr invalid [ 6268.695249] audio ch0: lock [ 6268.695290] bcm_audio_decoder_sample_rate_change_isr: 2 callbacks suppressed [ 6268.695308] audio ch0: sample rate changed isr (48000) [ 6268.788212] VIDEO0: first pts 0xd6732a70 [ 6268.937675] VIDEO0: enable tsm [ 6269.303837] audio ch0: status ready isr [ 6270.269148] audio ch0: decoder stop [ 6270.289694] stop AUDIO0 on dmx 16 pid:133 [ 6270.289705] audio ch0: xpt stop [ 6270.290178] VIDEO0: update_trickmode, sm=0, ff=4 [ 6270.290186] -> fast forward rate 4, mode 1 [ 6270.290412] -> (STC-)trickmode active, TSM [ 6270.296257] -> speedup=4,slowdown=0 [ 6270.328231] VIDEO0: first pts 0xd678a8b0 [ 6270.328310] VIDEO0: update_trickmode, sm=0, ff=4 [ 6270.328330] -> fast forward rate 4, mode 1 [ 6270.328339] -> (STC-)trickmode active, TSM [ 6270.328359] -> speedup=4,slowdown=0 [ 6272.600808] VIDEO0: update_trickmode, sm=0, ff=8 [ 6272.600821] -> fast forward rate 8, mode 0 [ 6272.606735] -> (STC-)trickmode active, TSM [ 6272.606746] -> speedup=8,slowdown=0 [ 6272.628263] VIDEO0: first pts 0xd68ed9f0 [ 6272.628447] VIDEO0: update_trickmode, sm=0, ff=8 [ 6272.628461] -> fast forward rate 8, mode 0 [ 6272.628468] -> (STC-)trickmode active, TSM [ 6272.628478] -> speedup=8,slowdown=0 [ 6273.727022] VIDEO0: flush video decoder [ 6273.733694] start AUDIO0 on dmx 16 pid:133 index 5 [ 6273.733706] audio ch0: xpt start [ 6273.733910] audio ch0: MPEG [ 6273.733997] audio ch0: decoder start [ 6273.734009] audio ch0 delay 45000 (500 ms) [ 6273.734015] VIDEO0: total delay: 45000 (500 ms) [ 6273.734669] VIDEO0: update_trickmode, sm=0, ff=0 [ 6273.734678] -> fast forward rate 0, mode 2 [ 6273.740250] -> no trickmode active, TSM [ 6273.748623] -> speedup=1,slowdown=0 [ 6273.756891] audio ch0: first pts 0xd69871dc, stc 0xd69afcea, pcr invalid [ 6273.759698] audio ch0: lock [ 6273.759736] audio ch0: sample rate changed isr (48000) [ 6273.888300] VIDEO0: first pts 0xd69ab770 [ 6274.038762] VIDEO0: enable tsm [ 6274.368532] audio ch0: status ready isr [ 7103.679124] audio ch0: flush audio decode [ 7103.699102] audio ch0 delay 45000 (500 ms) [ 7103.699111] VIDEO0: total delay: 45000 (500 ms) [ 7103.699369] VIDEO0: flush video decoder [ 7103.707667] VIDEO0 use saved pts 0xdb0b5cb0 (ret 0) [ 7103.707848] VIDEO0 use saved pts 0xdb0b5cb0 (ret 0) [ 7103.708107] VIDEO0 use saved pts 0xdb0b5cb0 (ret 0) [ 7103.710407] VIDEO0 use saved pts 0xdb0b5cb0 (ret 0) [ 7103.711553] VIDEO0 use saved pts 0xdb0b5cb0 (ret 0) [ 7103.711899] VIDEO0 use saved pts 0xdb0b5cb0 (ret 0) [ 7103.775244] audio ch0: first pts 0xdca7a05c, stc 0xdb0c3078, pcr invalid [ 7103.777053] audio ch0: lock [ 7103.777081] audio ch0: sample rate changed isr (48000) [ 7103.903202] VIDEO0: first pts 0xdca93790 [ 7104.053496] VIDEO0: enable tsm [ 7104.386744] audio ch0: status ready isr [ 7110.112707] audio ch0: flush audio decode [ 7110.133426] audio ch0 delay 45000 (500 ms) [ 7110.133436] VIDEO0: total delay: 45000 (500 ms) [ 7110.133743] VIDEO0: flush video decoder [ 7110.140628] VIDEO0 use saved pts 0xdcaf88c0 (ret 0) [ 7110.140893] audio ch0: use saved pts 0xdcaf787c (0x0) (ret 1) [ 7110.140911] audio ch0: decoder stop [ 7110.149788] stop AUDIO0 on dmx 16 pid:133 [ 7110.149797] audio ch0: xpt stop [ 7110.150240] VIDEO0: update_trickmode, sm=0, ff=1 [ 7110.150247] -> fast forward rate 1, mode 0 [ 7110.150473] -> trickmode active, VSYNC [ 7110.156142] -> speedup=1,slowdown=0 [ 7110.157494] VIDEO0 use saved pts 0xdcaf88c0 (ret 0) [ 7110.157706] VIDEO0 use saved pts 0xdcaf88c0 (ret 0) [ 7110.157908] VIDEO0 use saved pts 0xdcaf88c0 (ret 0) [ 7110.158703] VIDEO0 use saved pts 0xdcaf88c0 (ret 0) [ 7110.158995] VIDEO0 use saved pts 0xdcaf88c0 (ret 0) [ 7110.243297] VIDEO0: first pts 0xdcb077d0 [ 7110.243494] VIDEO0: update_trickmode, sm=0, ff=1 [ 7110.243507] -> fast forward rate 1, mode 0 [ 7110.243515] -> trickmode active, VSYNC [ 7110.243528] -> speedup=1,slowdown=0 [ 7112.654722] VIDEO0: flush video decoder [ 7112.660752] start AUDIO0 on dmx 16 pid:133 index 5 [ 7112.660762] audio ch0: xpt start [ 7112.660935] audio ch0: MPEG [ 7112.661008] audio ch0: decoder start [ 7112.661018] audio ch0 delay 45000 (500 ms) [ 7112.661024] VIDEO0: total delay: 45000 (500 ms) [ 7112.662013] VIDEO0: update_trickmode, sm=0, ff=0 [ 7112.662022] -> fast forward rate 0, mode 2 [ 7112.667685] -> no trickmode active, TSM [ 7112.673151] -> speedup=1,slowdown=0 [ 7112.676559] VIDEO0 use saved pts 0xdc76e650 (ret 0) [ 7112.677959] VIDEO0 use saved pts 0xdc76e650 (ret 0) [ 7112.680018] VIDEO0 use saved pts 0xdc76e650 (ret 0) [ 7112.697549] audio ch0: first pts 0xdc75983c, stc 0xdcb3c994, pcr invalid [ 7112.700320] audio ch0: lock [ 7112.700351] audio ch0: sample rate changed isr (48000) [ 7112.763381] VIDEO0: first pts 0xdc76e650 [ 7112.913686] VIDEO0: enable tsm [ 7115.049128] audio ch0: decoder stop [ 7115.069681] stop AUDIO0 on dmx 16 pid:133 [ 7115.069693] audio ch0: xpt stop [ 7115.070193] VIDEO0: update_trickmode, sm=0, ff=4 [ 7115.070203] -> fast forward rate 4, mode 1 [ 7115.070533] -> (STC-)trickmode active, TSM [ 7115.076373] -> speedup=4,slowdown=0 [ 7115.103412] VIDEO0: first pts 0xdc7d4590 [ 7115.103565] VIDEO0: update_trickmode, sm=0, ff=4 [ 7115.103578] -> fast forward rate 4, mode 1 [ 7115.103584] -> (STC-)trickmode active, TSM [ 7115.103593] -> speedup=4,slowdown=0 [ 7116.735965] VIDEO0: flush video decoder [ 7116.742265] start AUDIO0 on dmx 16 pid:133 index 5 [ 7116.742278] audio ch0: xpt start [ 7116.742464] audio ch0: MPEG [ 7116.742535] audio ch0: decoder start [ 7116.742545] audio ch0 delay 45000 (500 ms) [ 7116.742552] VIDEO0: total delay: 45000 (500 ms) [ 7116.743639] VIDEO0: update_trickmode, sm=0, ff=0 [ 7116.743649] -> fast forward rate 0, mode 2 [ 7116.749336] -> no trickmode active, TSM [ 7116.749541] audio ch0: first pts 0xdc83d53c, stc 0xdc862178, pcr invalid [ 7116.750163] audio ch0: lock [ 7116.750190] bcm_audio_decoder_sample_rate_change_isr: 1 callbacks suppressed [ 7116.750195] audio ch0: sample rate changed isr (48000) [ 7116.754884] -> speedup=1,slowdown=0 [ 7116.823431] VIDEO0: first pts 0xdc860f90 [ 7116.972730] VIDEO0: enable tsm [ 7117.345125] audio ch0: status ready isr [ 7656.813585] stop VIDEO0 on dmx 16 pid:cf [ 7656.814360] VIDEO0: stop decode [ 7656.814633] audio ch0: decoder stop [ 7656.835146] stop AUDIO0 on dmx 16 pid:133 [ 7656.835156] audio ch0: xpt stop [ 7656.835163] sync0 1fff no decoder left in sync handling so disable [ 7656.835167] sync0: was last pcroffset0 user so free it [ 7656.836357] demux16 is already set to DVR source!! [ 7656.865781] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7656.867180] start AUDIO0 on dmx 16 pid:133 index 3 [ 7656.867192] audio ch0: xpt start [ 7656.867216] sync0 is off so we enable it [ 7656.867222] sync0: allocated pcroffset 0 [ 7656.867232] sync0: playback! [ 7656.868333] audio ch0: MPEG [ 7656.868653] audio ch0: decoder start [ 7656.868663] audio ch0 delay 45000 (500 ms) [ 7656.868667] VIDEO0: total delay: 0 (0 ms) [ 7656.869518] audio ch0: first pts 0xdf6968fc, stc 0xdf697de8, pcr invalid [ 7656.869600] audio ch0: lock [ 7656.869625] audio ch0: sample rate changed isr (48000) [ 7656.870946] start VIDEO0 on dmx 16 pid:cf index 6 [ 7656.870984] VIDEO0: total delay: 45000 (500 ms) [ 7656.876445] VIDEO0: start decode [ 7656.879821] audio ch0: flush audio decode [ 7656.883746] audio ch0 delay 45000 (500 ms) [ 7656.883755] VIDEO0: total delay: 45000 (500 ms) [ 7656.883956] VIDEO0: flush video decoder [ 7656.883966] VIDEO0 use saved pts 0xdf688fd0 (ret 0) [ 7656.912742] audio ch0: first pts 0xdf68069c, stc 0xdf6960b6, pcr invalid [ 7656.915912] audio ch0: lock [ 7656.915941] audio ch0: sample rate changed isr (48000) [ 7657.033139] VIDEO0: first pts 0xdf698cf0 [ 7657.183317] VIDEO0: enable tsm [ 7657.524386] audio ch0: status ready isr [ 7665.518262] audio ch0: decoder stop [ 7665.542774] stop AUDIO0 on dmx 16 pid:133 [ 7665.542786] audio ch0: xpt stop [ 7665.545935] VIDEO0: update_trickmode, sm=0, ff=4 [ 7665.545949] -> fast forward rate 4, mode 1 [ 7665.546185] -> (STC-)trickmode active, TSM [ 7665.552583] -> speedup=4,slowdown=0 [ 7665.557947] VIDEO0 use saved pts 0xdf71e670 (ret 0) [ 7665.558126] VIDEO0 use saved pts 0xdf71e670 (ret 0) [ 7665.565016] VIDEO0 use saved pts 0xdf71e670 (ret 0) [ 7665.566720] VIDEO0 use saved pts 0xdf71e670 (ret 0) [ 7665.567214] VIDEO0 use saved pts 0xdf71e670 (ret 0) [ 7665.593293] VIDEO0: first pts 0xdf7a07b0 [ 7665.593360] VIDEO0: update_trickmode, sm=0, ff=4 [ 7665.593381] -> fast forward rate 4, mode 1 [ 7665.593388] -> (STC-)trickmode active, TSM [ 7665.593397] -> speedup=4,slowdown=0 [ 7668.318619] VIDEO0: flush video decoder [ 7668.325335] start AUDIO0 on dmx 16 pid:133 index 3 [ 7668.325346] audio ch0: xpt start [ 7668.325541] audio ch0: MPEG [ 7668.325614] audio ch0: decoder start [ 7668.325624] audio ch0 delay 45000 (500 ms) [ 7668.325630] VIDEO0: total delay: 45000 (500 ms) [ 7668.326429] VIDEO0: update_trickmode, sm=0, ff=0 [ 7668.326438] -> fast forward rate 0, mode 2 [ 7668.332039] -> no trickmode active, TSM [ 7668.337655] -> speedup=1,slowdown=0 [ 7668.342362] audio ch0: flush audio decode [ 7668.342373] audio ch0: use saved pts 0xdf73004c (0x17ee9b790) (ret 1) [ 7668.346718] audio ch0 delay 45000 (500 ms) [ 7668.346728] VIDEO0: total delay: 45000 (500 ms) [ 7668.346948] VIDEO0: flush video decoder [ 7668.346958] VIDEO0 use saved pts 0xdf8817b0 (ret 0) [ 7668.353947] audio ch0: flush audio decode [ 7668.353959] audio ch0: use saved pts 0xdf73004c (0x17ee9b790) (ret 1) [ 7668.358929] audio ch0 delay 45000 (500 ms) [ 7668.358938] VIDEO0: total delay: 45000 (500 ms) [ 7668.359128] VIDEO0: flush video decoder [ 7668.359138] VIDEO0 use saved pts 0xdf8817b0 (ret 0) [ 7668.440742] stop VIDEO0 on dmx 16 pid:cf [ 7668.440764] VIDEO0 use saved pts 0xdf8817b0 (ret 0) [ 7668.441387] VIDEO0: stop decode [ 7668.441686] audio ch0: use saved pts 0xdf73004c (0x197d75800) (ret 1) [ 7668.441707] audio ch0: decoder stop [ 7668.445188] stop AUDIO0 on dmx 16 pid:133 [ 7668.445195] audio ch0: xpt stop [ 7668.445201] sync0 1fff no decoder left in sync handling so disable [ 7668.445205] sync0: was last pcroffset0 user so free it [ 7668.446848] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7668.447304] start PCR0 on dmx 0 pid:cf index 13 [ 7668.447841] start AUDIO0 on dmx 0 pid:133 index 14 [ 7668.447848] audio ch0: xpt start [ 7668.447856] audio ch0: reset GA threshold to 8ms [ 7668.447871] sync0 is off so we enable it [ 7668.447877] sync0: allocated pcroffset 0 [ 7668.447889] sync0: demux-index 0 pcr pid cf [ 7668.447902] sync0: using 13 as pcr pidchannel [ 7668.447920] audio ch0: MPEG [ 7668.448155] audio ch0: decoder start [ 7668.448164] audio ch0 delay 18000 (200 ms) [ 7668.448169] VIDEO0: total delay: 18000 (200 ms) [ 7668.449306] start VIDEO0 on dmx 0 pid:cf index 13 [ 7668.449344] VIDEO0: total delay: 18000 (200 ms) [ 7668.451143] audio ch0: first pts 0xdfd03dfc, stc 0xdf8913de, pcr invalid [ 7668.454635] VIDEO0: start decode [ 7668.473878] audio ch0: lock [ 7668.473908] audio ch0: sample rate changed isr (48000) [ 7668.473931] audio ch0: tsm fail [ 7669.273354] VIDEO0: first pts 0xdfd24010 [ 7669.423555] VIDEO0: enable tsm [ 7671.118795] start PCR1 on dmx 0 pid:cf index 13 [ 7671.119446] start VIDEO1 on dmx 0 pid:cf index 13 [ 7671.119483] sync1 is off so we enable it [ 7671.119488] sync1: use shared pcroffset 0 [ 7671.119501] sync1: demux-index 0 pcr pid cf [ 7671.119512] sync1: using 13 as pcr pidchannel [ 7671.119523] VIDEO1: total delay: 18000 (200 ms) [ 7671.124878] VIDEO1: start decode [ 7671.469665] bcm_audio_decoder_tsm_fail_isr: 2 callbacks suppressed [ 7671.469690] audio ch0: tsm fail [ 7671.469701] audio ch0: pts error 0 PTS 0xdfd3eefc, STC 0xdfd444c6, type 1 [ 7671.469715] audio ch0: tsm fail [ 7671.469724] audio ch0: pts error 0 PTS 0xdfd3f76c, STC 0xdfd444c8, type 1 [ 7671.469736] audio ch0: tsm fail [ 7671.469743] audio ch0: pts error 0 PTS 0xdfd3ffdc, STC 0xdfd444ca, type 0 [ 7672.433139] VIDEO1: first pts 0xdfd6a510 [ 7672.583599] VIDEO1: enable tsm [ 7677.073706] stop PCR1 on dmx 0 pid:cf [ 7677.073901] stop VIDEO1 on dmx 0 pid:cf [ 7677.078133] VIDEO1: stop decode [ 7677.078147] sync1 1fff no decoder left in sync handling so disable [ 7677.078151] sync1: more pcroffset0 users left [ 7677.578674] VIDEO1: mute pip delayed ch state 0 [ 7680.973842] audio ch0: unlock [ 7789.608049] stop PCR0 on dmx 0 pid:cf [ 7789.608223] stop VIDEO0 on dmx 0 pid:cf [ 7789.608868] VIDEO0: stop decode [ 7789.609328] audio ch0: decoder stop [ 7789.629693] stop AUDIO0 on dmx 0 pid:133 [ 7789.629704] audio ch0: xpt stop [ 7789.629712] sync0 1fff no decoder left in sync handling so disable [ 7789.629717] sync0: was last pcroffset0 user so free it [ 7789.645926] si2183(0): start tune freq 1265000, sr 27500000, delsys 6 [ 7789.646409] input0 set to A1 [ 7789.646417] tsmux set tsports reg 00 to val 0a [ 7789.648285] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7789.648778] start PCR0 on dmx 0 pid:c9 index 2 [ 7789.649229] start AUDIO0 on dmx 0 pid:12d index 1 [ 7789.649237] audio ch0: xpt start [ 7789.649257] sync0 is off so we enable it [ 7789.649262] sync0: allocated pcroffset 0 [ 7789.649275] sync0: demux-index 0 pcr pid c9 [ 7789.649287] sync0: using 2 as pcr pidchannel [ 7789.649308] audio ch0: MPEG [ 7789.649538] audio ch0: decoder start [ 7789.649547] audio ch0 delay 18000 (200 ms) [ 7789.649555] VIDEO0: total delay: 18000 (200 ms) [ 7789.650638] start VIDEO0 on dmx 0 pid:c9 index 2 [ 7789.650705] VIDEO0: total delay: 18000 (200 ms) [ 7789.655954] VIDEO0: start decode [ 7789.753222] si2183(0): found carrier after 107 ms [ 7789.753232] si2183(0): final lock after 107 ms [ 7789.905233] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7790.074837] audio ch0: first pts 0x1ccfdc40c, stc 0x1ccfda5a6, pcr valid [ 7790.097529] audio ch0: lock [ 7790.097568] audio ch0: sample rate changed isr (48000) [ 7790.406196] audio ch0: status ready isr [ 7790.875514] VIDEO0: first pts 0x1ccff80b4 [ 7791.025709] VIDEO0: enable tsm [ 7792.623855] set mute 1 1 [ 7793.255074] set mute 0 1 [ 7799.155071] stop PCR0 on dmx 0 pid:c9 [ 7799.155226] stop VIDEO0 on dmx 0 pid:c9 [ 7799.156228] VIDEO0: stop decode [ 7799.156715] audio ch0: decoder stop [ 7799.177127] stop AUDIO0 on dmx 0 pid:12d [ 7799.177139] audio ch0: xpt stop [ 7799.177151] sync0 1fff no decoder left in sync handling so disable [ 7799.177159] sync0: was last pcroffset0 user so free it [ 7799.200310] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7799.200745] start PCR0 on dmx 0 pid:cf index 5 [ 7799.201255] start AUDIO0 on dmx 0 pid:133 index 4 [ 7799.201262] audio ch0: xpt start [ 7799.201282] sync0 is off so we enable it [ 7799.201287] sync0: allocated pcroffset 0 [ 7799.201299] sync0: demux-index 0 pcr pid cf [ 7799.201312] sync0: using 5 as pcr pidchannel [ 7799.201335] audio ch0: MPEG [ 7799.201567] audio ch0: decoder start [ 7799.201575] audio ch0 delay 18000 (200 ms) [ 7799.201580] VIDEO0: total delay: 18000 (200 ms) [ 7799.202778] start VIDEO0 on dmx 0 pid:cf index 5 [ 7799.202818] VIDEO0: total delay: 18000 (200 ms) [ 7799.208070] VIDEO0: start decode [ 7799.226282] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7799.349503] audio ch0: first pts 0xe084001c, stc 0xe083e1b2, pcr valid [ 7799.363024] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7799.372334] audio ch0: lock [ 7799.372362] audio ch0: sample rate changed isr (48000) [ 7799.680846] audio ch0: status ready isr [ 7800.775633] VIDEO0: first pts 0xe086dd90 [ 7800.940884] VIDEO0: enable tsm [ 7810.063481] stop PCR0 on dmx 0 pid:cf [ 7810.063635] stop VIDEO0 on dmx 0 pid:cf [ 7810.064546] VIDEO0: stop decode [ 7810.064991] audio ch0: decoder stop [ 7810.085550] stop AUDIO0 on dmx 0 pid:133 [ 7810.085559] audio ch0: xpt stop [ 7810.085568] sync0 1fff no decoder left in sync handling so disable [ 7810.085573] sync0: was last pcroffset0 user so free it [ 7810.108167] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7810.108595] start PCR0 on dmx 0 pid:c9 index 5 [ 7810.109003] start AUDIO0 on dmx 0 pid:12d index 4 [ 7810.109010] audio ch0: xpt start [ 7810.109060] sync0 is off so we enable it [ 7810.109067] sync0: allocated pcroffset 0 [ 7810.109083] sync0: demux-index 0 pcr pid c9 [ 7810.109100] sync0: using 5 as pcr pidchannel [ 7810.109306] audio ch0: MPEG [ 7810.109585] audio ch0: decoder start [ 7810.109594] audio ch0 delay 18000 (200 ms) [ 7810.109600] VIDEO0: total delay: 18000 (200 ms) [ 7810.110839] start VIDEO0 on dmx 0 pid:c9 index 5 [ 7810.110878] VIDEO0: total delay: 18000 (200 ms) [ 7810.116166] VIDEO0: start decode [ 7810.134806] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7810.152375] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7810.379173] audio ch0: first pts 0x1cd19a62c, stc 0x1cd1987c2, pcr valid [ 7810.401886] audio ch0: lock [ 7810.401931] audio ch0: sample rate changed isr (48000) [ 7810.710501] audio ch0: status ready isr [ 7811.775797] VIDEO0: first pts 0x1cd1c81b4 [ 7811.925106] VIDEO0: enable tsm [ 7828.146881] stop PCR0 on dmx 0 pid:c9 [ 7828.147036] stop VIDEO0 on dmx 0 pid:c9 [ 7828.147836] VIDEO0: stop decode [ 7828.148299] audio ch0: decoder stop [ 7828.168830] stop AUDIO0 on dmx 0 pid:12d [ 7828.168840] audio ch0: xpt stop [ 7828.168849] sync0 1fff no decoder left in sync handling so disable [ 7828.168855] sync0: was last pcroffset0 user so free it [ 7828.180608] input0 set to A1 [ 7828.180619] tsmux set tsports reg 00 to val 0a [ 7828.182295] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7828.182807] start PCR0 on dmx 0 pid:13f8 index 2 [ 7828.183333] start AUDIO0 on dmx 0 pid:13f9 index 1 [ 7828.183340] audio ch0: xpt start [ 7828.183386] sync0 is off so we enable it [ 7828.183392] sync0: allocated pcroffset 0 [ 7828.183403] sync0: demux-index 0 pcr pid 13f8 [ 7828.183414] sync0: using 2 as pcr pidchannel [ 7828.183570] audio ch0: MPEG [ 7828.183783] audio ch0: decoder start [ 7828.183790] audio ch0 delay 18000 (200 ms) [ 7828.183795] VIDEO0: total delay: 18000 (200 ms) [ 7828.184981] start VIDEO0 on dmx 0 pid:13f8 index 2 [ 7828.185021] VIDEO0: total delay: 18000 (200 ms) [ 7828.190214] VIDEO0: start decode [ 7828.398805] si2183(0): DVB-S(2) voltage disabled now [ 7828.605504] si2183(0): changed returnloss [ 7828.605514] si2183(0): start tune freq 530000000, bw 8000000, delsys 3, plp_id -1 [ 7829.013919] si2183(0): found carrier after 615 ms [ 7829.013930] si2183(0): final lock after 615 ms [ 7829.030806] audio ch0: first pts 0x19e233312, stc 0x19e230c5c, pcr valid [ 7829.053572] audio ch0: lock [ 7829.053613] audio ch0: sample rate changed isr (48000) [ 7829.072534] dvb_demux_feed_del: feed not in list (type=0 state=0 pid=ffff) [ 7829.385812] audio ch0: status ready isr [ 7829.856089] VIDEO0: first pts 0x19e252c86 [ 7829.856120] VIDEO0: resolution changed (704 576) [ 7830.005432] VIDEO0: enable tsm [ 7899.075230] set mute 1 1 [ 7899.103281] stop PCR0 on dmx 0 pid:13f8 [ 7899.103434] stop VIDEO0 on dmx 0 pid:13f8 [ 7899.104332] VIDEO0: stop decode [ 7899.104903] audio ch0: decoder stop [ 7899.108312] stop AUDIO0 on dmx 0 pid:13f9 [ 7899.108319] audio ch0: xpt stop [ 7899.108325] sync0 1fff no decoder left in sync handling so disable [ 7899.108329] sync0: was last pcroffset0 user so free it [ 7899.109090] set input to 1 [ 7899.109097] set fb to 2(0) [ 7899.144284] set sb to 3(0) [ 7899.219014] HDMI: CEC send ok! [ 7899.296687] HDMI: CEC send ok! [ 7899.369329] HDMI: CEC send ok! [ 7899.442024] HDMI: CEC send ok! [ 7899.554339] HDMI: CEC send ok! [ 7905.114094] si2183(0): DVB-S(2) voltage disabled now