Bug #4428

mpegts: too much queued input data (over 50MB), discarding new

Added by Ivan debilas 4 months ago. Updated 4 months ago.

Status:NewStart date:2017-06-13
Priority:NormalDue date:
Assignee:-% Done:

0%

Category:DVB
Target version:-
Found in version:4.3-218~gea2d89950 Affected Versions:

Description

last tvheadend from git, 2xTBS 6909

after 12-24h tvheadend crash / stop answer with error: mpegts: too much queued input data (over 50MB), discarding new

gdb.txt Magnifier (474 KB) Ivan debilas, 2017-06-19 01:00

History

#1 Updated by Ivan debilas 4 months ago

log before crash:

2017-06-13 16:11:44.731 [WARNING]:webui: Stop streaming /stream/channelnumber/3025, timeout waiting for packets
2017-06-13 16:11:45.131 [WARNING]:webui: Stop streaming /stream/channelnumber/4025, timeout waiting for packets
2017-06-13 16:11:45.167 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:45.232 [WARNING]:webui: Stop streaming /stream/channelnumber/4068, timeout waiting for packets
2017-06-13 16:11:45.332 [WARNING]:webui: Stop streaming /stream/channelnumber/1034, timeout waiting for packets
2017-06-13 16:11:45.432 [WARNING]:webui: Stop streaming /stream/channelnumber/1035, timeout waiting for packets
2017-06-13 16:11:45.432 [WARNING]:webui: Stop streaming /stream/channelnumber/2014, timeout waiting for packets
2017-06-13 16:11:45.532 [WARNING]:webui: Stop streaming /stream/channelnumber/4060, timeout waiting for packets
2017-06-13 16:11:45.732 [WARNING]:webui: Stop streaming /stream/channelnumber/1005, timeout waiting for packets
2017-06-13 16:11:45.977 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:46.232 [WARNING]:webui: Stop streaming /stream/channelnumber/1018, timeout waiting for packets
2017-06-13 16:11:46.332 [WARNING]:webui: Stop streaming /stream/channelnumber/2047, timeout waiting for packets
2017-06-13 16:11:46.472 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:46.532 [WARNING]:webui: Stop streaming /stream/channelnumber/1027, timeout waiting for packets
2017-06-13 16:11:46.532 [WARNING]:webui: Stop streaming /stream/channelnumber/4027, timeout waiting for packets
2017-06-13 16:11:46.732 [WARNING]:webui: Stop streaming /stream/channelnumber/1034, timeout waiting for packets
2017-06-13 16:11:47.033 [WARNING]:webui: Stop streaming /stream/channelnumber/3030, timeout waiting for packets
2017-06-13 16:11:47.633 [WARNING]:webui: Stop streaming /stream/channelnumber/3042, timeout waiting for packets
2017-06-13 16:11:47.633 [WARNING]:webui: Stop streaming /stream/channelnumber/2051, timeout waiting for packets
2017-06-13 16:11:47.633 [WARNING]:webui: Stop streaming /stream/channelnumber/3053, timeout waiting for packets
2017-06-13 16:11:47.633 [WARNING]:webui: Stop streaming /stream/channelnumber/3016, timeout waiting for packets
2017-06-13 16:11:47.933 [WARNING]:webui: Stop streaming /stream/channelnumber/2048, timeout waiting for packets
2017-06-13 16:11:47.933 [WARNING]:webui: Stop streaming /stream/channelnumber/4024, timeout waiting for packets
2017-06-13 16:11:48.033 [WARNING]:webui: Stop streaming /stream/channelnumber/4004, timeout waiting for packets
2017-06-13 16:11:48.033 [WARNING]:webui: Stop streaming /stream/channelnumber/3002, timeout waiting for packets
2017-06-13 16:11:48.434 [WARNING]:webui: Stop streaming /stream/channelnumber/2049, timeout waiting for packets
2017-06-13 16:11:48.634 [WARNING]:webui: Stop streaming /stream/channelnumber/1022, timeout waiting for packets
2017-06-13 16:11:48.734 [WARNING]:webui: Stop streaming /stream/channelnumber/2036, timeout waiting for packets
2017-06-13 16:11:48.934 [WARNING]:webui: Stop streaming /stream/channelnumber/4045, timeout waiting for packets
2017-06-13 16:11:48.934 [WARNING]:webui: Stop streaming /stream/channelnumber/4013, timeout waiting for packets
2017-06-13 16:11:49.134 [WARNING]:webui: Stop streaming /stream/channelnumber/4071, timeout waiting for packets
2017-06-13 16:11:49.134 [WARNING]:webui: Stop streaming /stream/channelnumber/2030, timeout waiting for packets
2017-06-13 16:11:49.835 [WARNING]:webui: Stop streaming /stream/channelnumber/3038, timeout waiting for packets
2017-06-13 16:11:50.135 [WARNING]:webui: Stop streaming /stream/channelnumber/1006, timeout waiting for packets
2017-06-13 16:11:50.235 [WARNING]:webui: Stop streaming /stream/channelnumber/2030, timeout waiting for packets
2017-06-13 16:11:50.335 [WARNING]:webui: Stop streaming /stream/channelnumber/2008, timeout waiting for packets
2017-06-13 16:11:50.435 [WARNING]:webui: Stop streaming /stream/channelnumber/3053, timeout waiting for packets
2017-06-13 16:11:50.435 [WARNING]:webui: Stop streaming /stream/channelnumber/4039, timeout waiting for packets
2017-06-13 16:11:50.935 [WARNING]:webui: Stop streaming /stream/channelnumber/4005, timeout waiting for packets
2017-06-13 16:11:51.036 [WARNING]:webui: Stop streaming /stream/channelnumber/2051, timeout waiting for packets
2017-06-13 16:11:51.136 [WARNING]:webui: Stop streaming /stream/channelnumber/2004, timeout waiting for packets
2017-06-13 16:11:51.336 [WARNING]:webui: Stop streaming /stream/channelnumber/3023, timeout waiting for packets
2017-06-13 16:11:51.636 [WARNING]:webui: Stop streaming /stream/channelnumber/3033, timeout waiting for packets
2017-06-13 16:11:51.936 [WARNING]:webui: Stop streaming /stream/channelnumber/3010, timeout waiting for packets
2017-06-13 16:11:52.637 [WARNING]:webui: Stop streaming /stream/channelnumber/4038, timeout waiting for packets
2017-06-13 16:11:52.742 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.838 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.841 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.843 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.843 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.843 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.921 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.937 [WARNING]:webui: Stop streaming /stream/channelnumber/4063, timeout waiting for packets
2017-06-13 16:11:52.938 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:52.942 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:53.038 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:53.137 [WARNING]:webui: Stop streaming /stream/channelnumber/3017, timeout waiting for packets
2017-06-13 16:11:53.240 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:53.344 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:53.437 [WARNING]:webui: Stop streaming /stream/channelnumber/3018, timeout waiting for packets
2017-06-13 16:11:53.837 [WARNING]:webui: Stop streaming /stream/channelnumber/3032, timeout waiting for packets
2017-06-13 16:11:55.038 [WARNING]:webui: Stop streaming /stream/channelnumber/4002, timeout waiting for packets
2017-06-13 16:11:55.038 [WARNING]:webui: Stop streaming /stream/channelnumber/3058, timeout waiting for packets
2017-06-13 16:11:55.140 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:55.687 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:55.687 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:55.687 [WARNING]:webui: Stop streaming /stream/channelnumber/1019, muxer reported errors
2017-06-13 16:11:55.687 [WARNING]:webui: Stop streaming /stream/channelnumber/2008, muxer reported errors
2017-06-13 16:11:55.943 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:55.943 [WARNING]:webui: Stop streaming /stream/channelnumber/1024, muxer reported errors
2017-06-13 16:11:55.943 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:56.039 [WARNING]:webui: Stop streaming /stream/channelnumber/4026, timeout waiting for packets
2017-06-13 16:11:56.445 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:56.615 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:11:56.711 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:56.711 [WARNING]:webui: Stop streaming /stream/channelnumber/3085, muxer reported errors
2017-06-13 16:11:56.711 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:56.711 [WARNING]:webui: Stop streaming /stream/channelnumber/1043, muxer reported errors
2017-06-13 16:11:56.967 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:56.967 [WARNING]:webui: Stop streaming /stream/channelnumber/4024, muxer reported errors
2017-06-13 16:11:57.139 [WARNING]:webui: Stop streaming /stream/channelnumber/4035, timeout waiting for packets
2017-06-13 16:11:57.223 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.223 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.223 [WARNING]:webui: Stop streaming /stream/channelnumber/3016, muxer reported errors
2017-06-13 16:11:57.223 [WARNING]:webui: Stop streaming /stream/channelnumber/1027, muxer reported errors
2017-06-13 16:11:57.440 [WARNING]:webui: Stop streaming /stream/channelnumber/1005, timeout waiting for packets
2017-06-13 16:11:57.479 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.479 [WARNING]:webui: Stop streaming /stream/channelnumber/2020, muxer reported errors
2017-06-13 16:11:57.479 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.479 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.479 [WARNING]:webui: Stop streaming /stream/channelnumber/3054, muxer reported errors
2017-06-13 16:11:57.479 [WARNING]:webui: Stop streaming /stream/channelnumber/2027, muxer reported errors
2017-06-13 16:11:57.640 [WARNING]:webui: Stop streaming /stream/channelnumber/3039, timeout waiting for packets
2017-06-13 16:11:57.735 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.735 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.735 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.735 [WARNING]:webui: Stop streaming /stream/channelnumber/3030, muxer reported errors
2017-06-13 16:11:57.735 [WARNING]:webui: Stop streaming /stream/channelnumber/4029, muxer reported errors
2017-06-13 16:11:57.735 [WARNING]:webui: Stop streaming /stream/channelnumber/3029, muxer reported errors
2017-06-13 16:11:57.740 [WARNING]:webui: Stop streaming /stream/channelnumber/2018, timeout waiting for packets
2017-06-13 16:11:57.991 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:57.991 [WARNING]:webui: Stop streaming /stream/channelnumber/2041, muxer reported errors
2017-06-13 16:11:58.247 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.247 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.247 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.247 [WARNING]:webui: Stop streaming /stream/channelnumber/4071, muxer reported errors
2017-06-13 16:11:58.247 [WARNING]:webui: Stop streaming /stream/channelnumber/4022, muxer reported errors
2017-06-13 16:11:58.247 [WARNING]:webui: Stop streaming /stream/channelnumber/3021, muxer reported errors
2017-06-13 16:11:58.503 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.503 [WARNING]:webui: Stop streaming /stream/channelnumber/2056, muxer reported errors
2017-06-13 16:11:58.503 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.503 [WARNING]:webui: Stop streaming /stream/channelnumber/3025, muxer reported errors
2017-06-13 16:11:58.759 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.759 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.759 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:58.759 [WARNING]:webui: Stop streaming /stream/channelnumber/2013, muxer reported errors
2017-06-13 16:11:58.759 [WARNING]:webui: Stop streaming /stream/channelnumber/3056, muxer reported errors
2017-06-13 16:11:58.759 [WARNING]:webui: Stop streaming /stream/channelnumber/4006, muxer reported errors
2017-06-13 16:11:59.015 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:59.015 [WARNING]:webui: Stop streaming /stream/channelnumber/4043, muxer reported errors
2017-06-13 16:11:59.527 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:11:59.527 [WARNING]:webui: Stop streaming /stream/channelnumber/3033, muxer reported errors
2017-06-13 16:11:59.641 [WARNING]:webui: Stop streaming /stream/channelnumber/1026, timeout waiting for packets
2017-06-13 16:12:00.295 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:12:00.295 [WARNING]:webui: Stop streaming /stream/channelnumber/3008, muxer reported errors
2017-06-13 16:12:00.551 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:12:00.551 [WARNING]:webui: Stop streaming /stream/channelnumber/2058, muxer reported errors
2017-06-13 16:12:01.319 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:12:01.319 [WARNING]:webui: Stop streaming /stream/channelnumber/1045, muxer reported errors
2017-06-13 16:12:01.442 [WARNING]:webui: Stop streaming /stream/channelnumber/3043, timeout waiting for packets
2017-06-13 16:12:01.831 [ ERROR]:pass: Live stream: Write failed -- Resource temporarily unavailable
2017-06-13 16:12:01.831 [WARNING]:webui: Stop streaming /stream/channelnumber/1017, muxer reported errors
2017-06-13 16:12:02.142 [WARNING]:webui: Stop streaming /stream/channelnumber/1022, timeout waiting for packets
2017-06-13 16:12:02.443 [WARNING]:webui: Stop streaming /stream/channelnumber/1032, timeout waiting for packets
2017-06-13 16:12:02.746 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:12:02.845 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:12:02.847 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:12:02.847 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-13 16:12:02.847 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new

#2 Updated by Pablo Rodríguez 4 months ago

Update to lastest, install tvheadend-gdb and report crash here.

#3 Updated by Ivan debilas 4 months ago

tvheadend not crashed, just stop streaming:

2017-06-14 15:05:50.252 [ DEBUG]:cwc: Received ECM reply (PID 6216) for service "TВ-21М" [0] even: 17.90.c6.6d.1a.e3.0e.0b odd: 41.88.59.22.b4.07.c0.7b (seqno: 7471 Req delay: 1124 ms)
2017-06-14 15:05:50.252 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "TВ-21М"
2017-06-14 15:05:50.252 [ DEBUG]:descrambler: info - service='TВ-21М' caid=0500(Viaccess) provid=000C00 ecmtime=1124 hops=1 reader='' from=':8082' protocol='newcamd'
2017-06-14 15:05:50.260 [ DEBUG]:cwc: Received ECM reply (PID 6014) for service "Euronews" [0] even: eb.16.dc.dd.b2.91.19.5c odd: d2.df.6b.1c.27.0d.e4.18 (seqno: 3841 Req delay: 944 ms)
2017-06-14 15:05:50.260 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "Euronews"
2017-06-14 15:05:50.260 [ DEBUG]:descrambler: info - service='Euronews' caid=0500(Viaccess) provid=000A00 ecmtime=944 hops=1 reader='' from=':8082' protocol='newcamd'
2017-06-14 15:05:50.313 [ DEBUG]:cwc: Received ECM reply (PID 6007) for service "Мульт" [0] even: 99.70.6f.78.34.b6.d3.bd odd: b8.9d.f0.45.0b.5f.f5.5f (seqno: 7464 Req delay: 1412 ms)
2017-06-14 15:05:50.313 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "Мульт"
2017-06-14 15:05:50.313 [ DEBUG]:descrambler: info - service='Мульт' caid=0500(Viaccess) provid=000A00 ecmtime=1412 hops=1 reader='' from='185.44.75.90:8082' protocol='newcamd'
2017-06-14 15:05:51.023 [ DEBUG]:cwc: Received ECM reply (PID 6208) for service "O!" [0] even: f1.1a.c6.d1.d3.95.41.a9 odd: 69.5a.b4.77.3e.12.ab.fb (seqno: 7472 Req delay: 1572 ms)
2017-06-14 15:05:51.023 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "O!"
2017-06-14 15:05:51.023 [ DEBUG]:descrambler: info - service='O!' caid=0500(Viaccess) provid=000C00 ecmtime=1572 hops=1 reader='' from=':8082' protocol='newcamd'
2017-06-14 15:05:56.725 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:56.742 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:56.790 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:56.869 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:56.875 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:56.902 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:57.084 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:57.225 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:58.197 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:58.217 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:58.473 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:59.070 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:59.607 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:05:59.869 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:06:06.728 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 15:06:06.730 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new

try to patch mpegts_input and set 900MB queue - still this error.

#4 Updated by Ivan debilas 4 months ago

tvheadend ver: 4.3-229~g2a77c08a9-dirty

#5 Updated by Ivan debilas 4 months ago

log with trace

2017-06-14 16:57:33.169 [ TRACE]:descrambler: ECM message 81 (section 128, len 340, pid 4116) for service "RTG TV"
2017-06-14 16:57:33.169 [ TRACE]:descrambler: ECM message 81 (section 128, len 340, pid 4116) for service "RTG TV"
2017-06-14 16:57:33.169 [ TRACE]:pcr: Adapter13/11900R/OCEAN-TV: change : 6645554941
2017-06-14 16:57:33.169 [ TRACE]:pcr: Adapter13/11900R/OCEAN-TV: change : 6645558124
2017-06-14 16:57:33.169 [ TRACE]:pcr: Adapter13/11900R/OCEAN-TV: change : 6645561316
2017-06-14 16:57:33.169 [ TRACE]:pcr: Adapter13/11900R/OCEAN-TV: change : 6645564524
2017-06-14 16:57:33.169 [ TRACE]:descrambler: ECM message 81 (section 210, len 113, pid 5107) for service "Тлум HD"
2017-06-14 16:57:33.169 [ TRACE]:descrambler: ECM message 81 (section 210, len 113, pid 5107) for service "Тлум HD"
2017-06-14 16:57:33.169 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #13 got 21620 bytes
2017-06-14 16:57:33.169 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #0 got 19176 bytes
2017-06-14 16:57:33.169 [ TRACE]:pcr: Adapter10/12265L/Россия 1: change : 801863847
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/Россия 1: change : 801867198
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/Россия 1: change : 801870502
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/Россия 1: change : 801873803
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/Россия 1: change : 801877082
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter9/12341L/Зоопарк: change : 729840472
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter9/12341L/Зоопарк: change : 729843680
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter9/12341L/Зоопарк: change : 729846824
2017-06-14 16:57:33.170 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #9 got 33276 bytes
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter4/12245R/JimJam: change : 40171124
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter4/12245R/JimJam: change : 40174332
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter4/12245R/JimJam: change : 40177435
2017-06-14 16:57:33.170 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #4 got 28576 bytes
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/РЕН ТВ: change : 801870173
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/РЕН ТВ: change : 801873463
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/РЕН ТВ: change : 801876785
2017-06-14 16:57:33.170 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #3 got 27636 bytes
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/МИР: change : 414957403
2017-06-14 16:57:33.170 [ TRACE]:pcr: Adapter10/12265L/МИР: change : 414960728
2017-06-14 16:57:33.170 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #10 got 29704 bytes
2017-06-14 16:57:33.171 [ TRACE]:descrambler: ECM message 80 (section 128, len 410, pid 4115) for service "РОДНОЕ КИНО"
2017-06-14 16:57:33.171 [ TRACE]:descrambler: ECM message 80 (section 128, len 410, pid 4115) for service "РОДНОЕ КИНО"
2017-06-14 16:57:33.171 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #2 got 18800 bytes
2017-06-14 16:57:33.171 [ TRACE]:pcr: Adapter8/11785R/МАТЧ! ФУТБОЛ 2: change : 154910382
2017-06-14 16:57:33.171 [ TRACE]:descrambler: ECM message 80 (section 128, len 384, pid 4113) for service "МУЗ"
2017-06-14 16:57:33.171 [ TRACE]:descrambler: ECM message 80 (section 128, len 384, pid 4113) for service "МУЗ"
2017-06-14 16:57:33.171 [ TRACE]:pcr: Adapter12/12015R/THT HD: change : 504114578
2017-06-14 16:57:33.171 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #12 got 37976 bytes
2017-06-14 16:57:33.171 [ TRACE]:pcr: Adapter6/12284R/ТЕЛЕКАФЕ: change : 165884893
2017-06-14 16:57:33.171 [ TRACE]:pcr: Adapter6/12284R/ТЕЛЕКАФЕ: change : 165888134
2017-06-14 16:57:33.171 [ TRACE]:pcr: Adapter6/12284R/ТЕЛЕКАФЕ: change : 165891287
2017-06-14 16:57:33.171 [ TRACE]:pcr: Adapter6/12284R/ТЕЛЕКАФЕ: change : 165894448
2017-06-14 16:57:33.171 [ TRACE]:descrambler: ECM message 81 (section 128, len 304, pid 4119) for service "Время: далекое и близкое"
2017-06-14 16:57:33.171 [ TRACE]:descrambler: ECM message 81 (section 128, len 304, pid 4119) for service "Время: далекое и близкое"
2017-06-14 16:57:33.171 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18800 bytes
2017-06-14 16:57:33.172 [ TRACE]:descrambler: ECM message 81 (section 128, len 446, pid 4111) for service "Первый образовательный"
2017-06-14 16:57:33.172 [ TRACE]:descrambler: ECM message 81 (section 128, len 446, pid 4111) for service "Первый образовательный"
2017-06-14 16:57:33.172 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #0 got 18800 bytes
2017-06-14 16:57:33.174 [ TRACE]:pcr: Adapter3/12207R/Bollywood HD: change : 500783039
2017-06-14 16:57:33.174 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #3 got 18988 bytes
2017-06-14 16:57:33.174 [ TRACE]:pcr: Adapter2/12130R/1HD: change : 744698714
2017-06-14 16:57:33.174 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #2 got 19176 bytes
2017-06-14 16:57:33.174 [ TRACE]:descrambler: ECM message 81 (section 210, len 128, pid 5101) for service "HTB HD"
2017-06-14 16:57:33.174 [ TRACE]:descrambler: ECM message 81 (section 210, len 128, pid 5101) for service "HTB HD"
2017-06-14 16:57:33.174 [ TRACE]:descrambler: ECM message 81 (section 128, len 388, pid 6019) for service "Сарафан"
2017-06-14 16:57:33.174 [ TRACE]:descrambler: ECM message 81 (section 128, len 388, pid 6019) for service "Сарафан"
2017-06-14 16:57:33.175 [ TRACE]:pcr: Adapter15/11938R/TV 1000 Comedy HD: change : 875319399
2017-06-14 16:57:33.175 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 27072 bytes
2017-06-14 16:57:33.175 [ DEBUG]:cwc: Received ECM reply (PID 6209) for service "Че" [0] even: 91.d1.17.79.ff.c9.cd.95 odd: d8.d7.ee.9d.b8.73.41.6c (seqno: 38955 Req delay: 724 ms)
2017-06-14 16:57:33.175 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Че"
2017-06-14 16:57:33.175 [ TRACE]:descrambler: Obtained CSA keys 91D11779FFC9CD95:D8D7EE9DB873416C pid 0000 from cwc-$IP-8082-0500 for service "Че"
2017-06-14 16:57:33.175 [ DEBUG]:descrambler: info - service='Че' caid=0500(Viaccess) provid=000C00 ecmtime=724 hops=1 reader='' from='$IP:8082' protocol='newcamd'
2017-06-14 16:57:33.175 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #0 got 38540 bytes
2017-06-14 16:57:33.176 [ TRACE]:pcr: Adapter4/12245R/Загородная жизнь: change : 40010576
2017-06-14 16:57:33.176 [ TRACE]:pcr: Adapter4/12245R/Радость моя: change : 40144513
2017-06-14 16:57:33.176 [ TRACE]:pcr: Adapter4/12245R/Продвижение: change : 38719639
2017-06-14 16:57:33.176 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #4 got 33464 bytes
2017-06-14 16:57:33.176 [ TRACE]:descrambler: ECM message 80 (section 210, len 137, pid 5104) for service "History HD"
2017-06-14 16:57:33.176 [ TRACE]:descrambler: ECM message 80 (section 210, len 137, pid 5104) for service "History HD"
2017-06-14 16:57:33.177 [ TRACE]:pcr: Adapter2/12130R/Первый канал HD: change : 746386952
2017-06-14 16:57:33.177 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #2 got 32336 bytes
2017-06-14 16:57:33.178 [ TRACE]:pcr: Adapter15/11938R/Viasat Sport HD: change : 420157518
2017-06-14 16:57:33.178 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 23124 bytes
2017-06-14 16:57:33.178 [ TRACE]:pcr: Adapter0/11823R/МАТЧ! ФУТБОЛ 1 HD: change : 504115472
2017-06-14 16:57:33.178 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #0 got 18800 bytes
2017-06-14 16:57:33.179 [ TRACE]:descrambler: ECM message 80 (section 128, len 360, pid 4112) for service "Детский"
2017-06-14 16:57:33.179 [ TRACE]:descrambler: ECM message 80 (section 128, len 360, pid 4112) for service "Детский"
2017-06-14 16:57:33.179 [ TRACE]:pcr: Adapter10/12265L/МАТЧ!: change : 801872667
2017-06-14 16:57:33.179 [ TRACE]:pcr: Adapter10/12265L/МАТЧ!: change : 801875964
2017-06-14 16:57:33.179 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #10 got 19176 bytes
2017-06-14 16:57:33.180 [ TRACE]:pcr: Adapter2/12130R/Mezzo Live HD: change : 747494634
2017-06-14 16:57:33.180 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #2 got 23312 bytes
2017-06-14 16:57:33.181 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 22936 bytes
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/8 канал: change : 38785613
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter0/11823R/Nickelodeon HD: change : 504286930
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Шансон-ТВ: change : 39677372
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Шансон-ТВ: change : 39680593
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Шансон-ТВ: change : 39683750
2017-06-14 16:57:33.182 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #0 got 18800 bytes
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Мир сериала: change : 39972404
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Мир сериала: change : 39975630
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Мир сериала: change : 39978745
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Мир сериала: change : 39981939
2017-06-14 16:57:33.182 [ TRACE]:pcr: Adapter4/12245R/Мир сериала: change : 39985078
2017-06-14 16:57:33.182 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #4 got 32712 bytes
2017-06-14 16:57:33.183 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #2 got 23312 bytes
2017-06-14 16:57:33.184 [ TRACE]:pcr: Adapter15/11938R/Viasat Nature/History HD: change : 875824618
2017-06-14 16:57:33.184 [ TRACE]:descrambler: ECM message 80 (section 128, len 434, pid 4101) for service "Россия 1"
2017-06-14 16:57:33.184 [ TRACE]:descrambler: ECM message 80 (section 128, len 434, pid 4101) for service "Россия 1"
2017-06-14 16:57:33.184 [ TRACE]:pcr: Adapter15/11938R/TV 1000 Premium HD: change : 875955283
2017-06-14 16:57:33.184 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 23124 bytes
2017-06-14 16:57:33.185 [ TRACE]:pcr: Adapter0/11823R/МАТЧ! ФУТБОЛ 2 HD: change : 533271317
2017-06-14 16:57:33.185 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #0 got 18988 bytes
2017-06-14 16:57:33.185 [ TRACE]:linuxdvb: TurboSight TBS 6909 DVB-S/S2 : DVB-S #11 - status 1 (001F)
2017-06-14 16:57:33.186 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #2 got 23312 bytes
2017-06-14 16:57:33.187 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 22936 bytes
2017-06-14 16:57:33.188 [ TRACE]:descrambler: ECM message 81 (section 128, len 380, pid 4106) for service "Nickelodeon HD"
2017-06-14 16:57:33.188 [ TRACE]:descrambler: ECM message 81 (section 128, len 380, pid 4106) for service "Nickelodeon HD"
2017-06-14 16:57:33.188 [ TRACE]:descrambler: ECM message 81 (section 128, len 130, pid 6202) for service "ABTO24"
2017-06-14 16:57:33.188 [ TRACE]:descrambler: ECM message 81 (section 128, len 130, pid 6202) for service "ABTO24"
2017-06-14 16:57:33.189 [ TRACE]:descrambler: ECM message 81 (section 128, len 324, pid 4101) for service "Nat Geo Wild HD"
2017-06-14 16:57:33.189 [ TRACE]:descrambler: ECM message 81 (section 128, len 324, pid 4101) for service "Nat Geo Wild HD"
2017-06-14 16:57:33.190 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 22936 bytes
2017-06-14 16:57:33.193 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18988 bytes
2017-06-14 16:57:33.196 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 26696 bytes
2017-06-14 16:57:33.200 [ TRACE]:pcr: Adapter15/11938R/EUROSPORT 2 HD: change : 504115605
2017-06-14 16:57:33.200 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18988 bytes
2017-06-14 16:57:33.203 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 27260 bytes
2017-06-14 16:57:33.203 [ TRACE]:linuxdvb: TurboSight TBS 6909 DVB-S/S2 : DVB-S #12 - checking FE status (ready)
2017-06-14 16:57:33.206 [ TRACE]:pcr: Adapter15/11938R/TV 1000 Comedy HD: change : 875322710
2017-06-14 16:57:33.207 [ TRACE]:pcr: Adapter15/11938R/FOX HD: change : 418331613
2017-06-14 16:57:33.207 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18800 bytes
2017-06-14 16:57:33.209 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18988 bytes
2017-06-14 16:57:33.209 [ DEBUG]:cwc: Received ECM reply (PID 6224) for service "КВН ТВ (ТЕСТ)" [0] even: 38.b6.02.f0.b8.2b.95.78 odd: eb.4b.85.bb.99.5e.9c.93 (seqno: 46174 Req delay
: 1028 ms)
2017-06-14 16:57:33.209 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "КВН ТВ (ТЕСТ)"
2017-06-14 16:57:33.209 [ TRACE]:descrambler: Obtained CSA keys 38B602F0B82B9578:EB4B85BB995E9C93 pid 0000 from cwc-$IP-8082-0500 for service "КВН ТВ (ТЕСТ)"
2017-06-14 16:57:33.209 [ DEBUG]:descrambler: info - service='КВН ТВ (ТЕСТ)' caid=0500(Viaccess) provid=000C00 ecmtime=1028 hops=1 reader='' from='$IP:8082' protocol='newcam
d'
2017-06-14 16:57:33.212 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 19364 bytes
2017-06-14 16:57:33.216 [ TRACE]:pcr: Adapter15/11938R/TV 1000 Premium HD: change : 875958612
2017-06-14 16:57:33.216 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 35156 bytes
2017-06-14 16:57:33.219 [ TRACE]:pcr: Adapter15/11938R/Viasat Sport HD: change : 420160514
2017-06-14 16:57:33.219 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 22748 bytes
2017-06-14 16:57:33.222 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18988 bytes
2017-06-14 16:57:33.225 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 18800 bytes
2017-06-14 16:57:33.228 [ TRACE]:pcr: Adapter15/11938R/Viasat Nature/History HD: change : 875827945
2017-06-14 16:57:33.228 [ TRACE]:mpegts: input TurboSight TBS 6909 DVB-S/S2 : DVB-S #15 got 31772 bytes
2017-06-14 16:57:33.231 [ TRACE]:descrambler: ECM message 81 (section 128, len 294, pid 5203) for service "TV 1000 Megahit HD"
2017-06-14 16:57:33.231 [ TRACE]:descrambler: ECM message 81 (section 128, len 294, pid 5203) for service "TV 1000 Megahit HD"
2017-06-14 16:57:33.251 [ TRACE]:linuxdvb: TurboSight TBS 6909 DVB-S/S2 : DVB-S #12 - status 1 (001F)
2017-06-14 16:57:33.287 [ DEBUG]:cwc: Received ECM reply (PID 6218) for service "БОБЁР" [0] even: 3b.6f.36.e0.f6.3d.26.59 odd: 6b.2d.e9.81.05.3f.83.c7 (seqno: 38954 Req delay: 1100 m
s)
2017-06-14 16:57:33.287 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "БОБЁР"
2017-06-14 16:57:33.287 [ TRACE]:descrambler: Obtained CSA keys 3B6F36E0F63D2659:6B2DE981053F83C7 pid 0000 from cwc-$IP-8082-0500 for service "БОБЁР"
2017-06-14 16:57:33.287 [ DEBUG]:descrambler: info - service='БОБЁР' caid=0500(Viaccess) provid=000C00 ecmtime=1100 hops=1 reader='' from='$IP:8082' protocol='newcamd'
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: pmt: pid 79 tableid 02 extraid 0000000000000849 len 93
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: pmt: section 0 last 0 ver 2 (ver 2 st 2 incomp 0 comp 1)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: pmt: skip, already complete (2)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: pmt: pid 77E tableid 02 extraid 0000000000004aae len 93
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: pmt: section 0 last 0 ver 2 (ver 2 st 2 incomp 0 comp 1)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: pmt: skip, already complete (2)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: cat: pid 01 tableid 01 extraid 0000000000000000 len 75
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: cat: section 0 last 0 ver 21 (ver 21 st 2 incomp 0 comp 1)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: cat: skip, already complete (2)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: cat: pid 01 tableid 01 extraid 0000000000000000 len 73
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: cat: section 0 last 0 ver 3 (ver 3 st 2 incomp 0 comp 1)
2017-06-14 16:57:33.293 [ TRACE]:tbl-base: cat: skip, already complete (2)
2017-06-14 16:57:33.453 [ DEBUG]:cwc: Received ECM reply (PID 6018) for service "Paramount Comedy" [0] even: d2.6f.66.a7.b4.13.52.19 odd: ae.0e.88.44.0c.9f.d0.7b (seqno: 38956 Req de
lay: 944 ms)
2017-06-14 16:57:33.453 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Paramount Comedy"
2017-06-14 16:57:33.453 [ TRACE]:descrambler: Obtained CSA keys D26F66A7B4135219:AE0E88440C9FD07B pid 0000 from cwc-$IP-8082-0500 for service "Paramount Comedy"
2017-06-14 16:57:33.453 [ DEBUG]:descrambler: info - service='Paramount Comedy' caid=0500(Viaccess) provid=000A00 ecmtime=944 hops=1 reader='' from='$IP:8082' protocol='newc
amd'
2017-06-14 16:57:33.552 [ DEBUG]:cwc: Received ECM reply (PID 6201) for service "Nat Geo Wild HD" [0] even: 75.31.41.e7.4c.53.65.04 odd: d4.37.5f.6a.35.5f.c6.5a (seqno: 38957 Req del
ay: 660 ms)
2017-06-14 16:57:33.552 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Nat Geo Wild HD"
2017-06-14 16:57:33.552 [ TRACE]:descrambler: Obtained CSA keys 753141E74C536504:D4375F6A355FC65A pid 0000 from cwc-$IP-8082-0500 for service "Nat Geo Wild HD"
2017-06-14 16:57:33.552 [ DEBUG]:descrambler: info - service='Nat Geo Wild HD' caid=0500(Viaccess) provid=000C00 ecmtime=660 hops=1 reader='' from='$IP:8082' protocol='newca
md'
2017-06-14 16:57:33.553 [ DEBUG]:cwc: Received ECM reply (PID 6201) for service "365 дней ТВ" [0] even: 62.f7.b9.12.40.1f.4d.ac odd: 06.c4.75.3f.93.f1.39.bd (seqno: 46175 Req delay:
1112 ms)
2017-06-14 16:57:33.553 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "365 дней ТВ"
2017-06-14 16:57:33.553 [ TRACE]:descrambler: Obtained CSA keys 62F7B912401F4DAC:06C4753F93F139BD pid 0000 from cwc-$IP-8082-0500 for service "365 дней ТВ"
2017-06-14 16:57:33.553 [ DEBUG]:descrambler: info - service='365 дней ТВ' caid=0500(Viaccess) provid=000C00 ecmtime=1112 hops=1 reader='' from='$IP:8082' protocol='newcamd'
2017-06-14 16:57:33.580 [ DEBUG]:cwc: Received ECM reply (PID 6210) for service "Охота и рыбалка" [0] even: ef.ac.4d.e8.84.4b.99.68 odd: 21.52.53.c6.c1.0e.26.f5 (seqno: 46176 Req del
ay: 860 ms)
2017-06-14 16:57:33.580 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "Охота и рыбалка"
2017-06-14 16:57:33.580 [ TRACE]:descrambler: Obtained CSA keys EFAC4DE8844B9968:215253C6C10E26F5 pid 0000 from cwc-$IP-8082-0500 for service "Охота и рыбалка"
2017-06-14 16:57:33.580 [ DEBUG]:descrambler: info - service='Охота и рыбалка' caid=0500(Viaccess) provid=000C00 ecmtime=860 hops=1 reader='' from='$IP:8082' protocol='newca
md'
2017-06-14 16:57:33.729 [ DEBUG]:cwc: Received ECM reply (PID 6206) for service "Viasat History" [0] even: 32.fc.04.32.64.d8.d4.10 odd: 15.65.3e.b8.7c.01.c0.3d (seqno: 46177 Req dela
y: 1004 ms)
2017-06-14 16:57:33.729 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "Viasat History"
2017-06-14 16:57:33.729 [ TRACE]:descrambler: Obtained CSA keys 32FC043264D8D410:15653EB87C01C03D pid 0000 from cwc-$IP-8082-0500 for service "Viasat History"
2017-06-14 16:57:33.729 [ DEBUG]:descrambler: info - service='Viasat History' caid=0500(Viaccess) provid=000C00 ecmtime=1004 hops=1 reader='' from='$IP:8082' protocol='newca
md'
2017-06-14 16:57:33.756 [ DEBUG]:cwc: Received ECM reply (PID 6211) for service "MTV Hits" [0] even: 77.c8.2c.6b.48.a7.d0.bf odd: 2a.d5.f2.f1.a9.ab.a3.f7 (seqno: 46179 Req delay: 684
ms)
2017-06-14 16:57:33.756 [ DEBUG]:descrambler: Both keys received, marking ECM start for odd key for service "MTV Hits"
2017-06-14 16:57:33.756 [ TRACE]:descrambler: Obtained CSA keys 77C82C6B48A7D0BF:2AD5F2F1A9ABA3F7 pid 0000 from cwc-$IP-8082-0500 for service "MTV Hits"
2017-06-14 16:57:33.756 [ DEBUG]:descrambler: info - service='MTV Hits' caid=0500(Viaccess) provid=000C00 ecmtime=684 hops=1 reader='' from='$IP:8082' protocol='newcamd'
2017-06-14 16:57:34.048 [ DEBUG]:cwc: Received ECM reply (PID 6211) for service "Первый образовательный" [0] even: 86.c4.50.9a.51.6b.71.2d odd: 5a.cd.2c.53.02.a2.7e.22 (seqno: 46178
Req delay: 1208 ms)
2017-06-14 16:57:34.048 [ DEBUG]:descrambler: Both keys received, marking ECM start for even key for service "Первый образовательный"
2017-06-14 16:57:34.048 [ TRACE]:descrambler: Obtained CSA keys 86C4509A516B712D:5ACD2C5302A27E22 pid 0000 from cwc-$IP-8082-0500 for service "Первый образовательный"
2017-06-14 16:57:34.048 [ DEBUG]:descrambler: info - service='Первый образовательный' caid=0500(Viaccess) provid=000C00 ecmtime=1208 hops=1 reader='' from='$IP:8082' protoco
l='newcamd'
2017-06-14 16:57:40.186 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.282 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.283 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.293 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.341 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.342 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.353 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.465 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.513 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.680 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:40.924 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:43.233 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:44.033 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:44.632 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.171 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.269 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.269 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.270 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.270 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.271 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.272 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.276 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.469 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.470 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.671 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:50.872 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:52.195 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:53.174 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:53.370 [ DEBUG]:webui: Stop streaming /stream/channelnumber/2047, client hung up
2017-06-14 16:57:53.974 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:57:54.573 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:58:00.178 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:58:00.275 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new
2017-06-14 16:58:00.276 [WARNING]:mpegts: too much queued input data (over 50MB), discarding new

#6 Updated by Ivan debilas 4 months ago

reinstall to ubuntu 16.04
last tvheadend: 4.3-229~g2a77c08-dirty

and the same error.

#7 Updated by Jaroslav Kysela 4 months ago

It should be determined in which loop tvh is when this condition occurs. It's not easy to debug this. Provide full backtrace: https://tvheadend.org/projects/tvheadend/wiki/Debugging#Dead-or-Live-Lock

#8 Updated by Ivan debilas 4 months ago

Attaching to program: /usr/bin/tvheadend, process 10321
[New LWP 10322]
[New LWP 10323]
[New LWP 10324]
[New LWP 10325]
[New LWP 10326]
[New LWP 10327]
[New LWP 10328]
[New LWP 10329]
[New LWP 10330]
[New LWP 10331]
[New LWP 10332]
[New LWP 10357]
[New LWP 10358]
[New LWP 10359]
[New LWP 10360]
[New LWP 10361]
[New LWP 10362]
[New LWP 10363]
[New LWP 10364]
[New LWP 10365]
[New LWP 10366]
[New LWP 10367]
[New LWP 10368]
[New LWP 10369]
[New LWP 10372]
[New LWP 10373]
[New LWP 10374]
[New LWP 10380]
[New LWP 10381]
[New LWP 10382]
[New LWP 10383]
[New LWP 10384]
[New LWP 10385]
[New LWP 10386]
[New LWP 10387]
[New LWP 10388]
[New LWP 10389]
[New LWP 10390]
[New LWP 10391]
[New LWP 10392]
[New LWP 10393]
[New LWP 10394]
[New LWP 10395]
[New LWP 10396]
[New LWP 10397]
[New LWP 10398]
[New LWP 10399]
[New LWP 10400]
[New LWP 10401]
[New LWP 10402]
[New LWP 10403]
[New LWP 10404]
[New LWP 10405]
[New LWP 10406]
[New LWP 10407]
[New LWP 10408]
[New LWP 10409]
[New LWP 10410]
[New LWP 10411]
[New LWP 10412]
[New LWP 10413]
[New LWP 12901]
[New LWP 31870]
[New LWP 31871]
[New LWP 7983]
[New LWP 7992]
[New LWP 18196]
[New LWP 18198]
[New LWP 18199]
[New LWP 18200]
[New LWP 18201]
[New LWP 18202]
[New LWP 18205]
[New LWP 18206]
[New LWP 18207]
[New LWP 18208]
[New LWP 18209]
[New LWP 18210]
[New LWP 18211]
[New LWP 18212]
[New LWP 18213]
[New LWP 18214]
[New LWP 18215]
[New LWP 18216]
[New LWP 18217]
[New LWP 18218]
[New LWP 18219]
[New LWP 18220]
[New LWP 18221]
[New LWP 18222]
[New LWP 18223]
[New LWP 18225]
[New LWP 18226]
[New LWP 18227]
[New LWP 18228]
[New LWP 18229]
[New LWP 18230]
[New LWP 18231]
[New LWP 18232]
[New LWP 18233]
[New LWP 18234]
[New LWP 18235]
[New LWP 18236]
[New LWP 18237]
[New LWP 18238]
[New LWP 18239]
[New LWP 18240]
[New LWP 18243]
[New LWP 18244]
[New LWP 18245]
[New LWP 18246]
[New LWP 18247]
[New LWP 18249]
[New LWP 18250]
[New LWP 18253]
[New LWP 18254]
[New LWP 18255]
[New LWP 18256]
[New LWP 18257]
[New LWP 18258]
[New LWP 18259]
[New LWP 18260]
[New LWP 18261]
[New LWP 18262]
[New LWP 18263]
[New LWP 18264]
[New LWP 18265]
[New LWP 18266]
[New LWP 18267]
[New LWP 18268]
[New LWP 18269]
[New LWP 18270]
[New LWP 18272]
[New LWP 18273]
[New LWP 18274]
[New LWP 18275]
[New LWP 18279]
[New LWP 18280]
[New LWP 18281]
[New LWP 18282]
[New LWP 18283]
[New LWP 18284]
[New LWP 18285]
[New LWP 18286]
[New LWP 18287]
[New LWP 18288]
[New LWP 18289]
[New LWP 18291]
[New LWP 18292]
[New LWP 18293]
[New LWP 18294]
[New LWP 18296]
[New LWP 18297]
[New LWP 18298]
[New LWP 18300]
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
__lll_lock_wait () at ../sysdeps/unix/sysv/linux/x86_64/lowlevellock.S:135
135 ../sysdeps/unix/sysv/linux/x86_64/lowlevellock.S: No such file or directory.
(gdb) continue
Continuing.

ubuntu 16.04, tvheadend 4.2.2 release (builded from sources)

#9 Updated by Ivan debilas 4 months ago

#10 Updated by Ivan debilas 4 months ago

2nd episode:

last tvheadend from git, debian 8.

http://185.44.75.93/gdb.txt
http://185.44.75.93/core.285033 (3.3G)

#11 Updated by Jaroslav Kysela 4 months ago

@Ivan : Could you use the core file and tvh binary which generated this core and do 'print global_lock' in gdb ?

#12 Updated by Ivan debilas 4 months ago

Could you use the core file??
use? maybe send?

what i need to do? except 'print global_lock' in gdb

please more explain. 4.0.10 release works fine, but EPG xmltv not work. 4.2.2 and latest stop work every 2h, but epg work

#13 Updated by Jaroslav Kysela 4 months ago

@Ivan: Try v4.3-246-gf4a01ae .

#14 Updated by Ivan debilas 4 months ago

(gdb) print global_lock
$1 = {__data = {__lock = 2, _count = 0, __owner = 367398, __nusers = 6, __kind = 0, __spins = 0, __elision = 0, __list = {_prev = 0x0, __next = 0x0}}, __size = "\002\000\000\000\000\000\000\000&\233\005\000\006", '\000' <repeats 26 times>, __align = 2}
(gdb) continue
Continuing.
[Thread 0x7f175a5f2700 (LWP 399414) exited]
[New Thread 0x7f175a5f2700 (LWP 399455)]

Tvheadend 4.3-246~gf4a01aec3-dirty

http://185.44.75.93/246_gf4a01aec3/gdb.txt
http://185.44.75.93/246_gf4a01aec3/core.367388 (3.4G)

#15 Updated by Jaroslav Kysela 4 months ago

v4.3-256-g5c51fb5

#16 Updated by Ivan debilas 4 months ago

CC              src/descrambler/cwc.o
src/descrambler/cwc.c: In function ‘cwc_service_destroy0’:
src/descrambler/cwc.c:1525:8: error: ‘i’ undeclared (first use in this function)
   for (i = 0; i < CWC_ES_PIDS; i++)
        ^
src/descrambler/cwc.c:1525:8: note: each undeclared identifier is reported only once for each function it appears in
src/descrambler/cwc.c:1523:10: error: unused variable ‘cwc’ [-Werror=unused-variable]
   cwc_t *cwc = ct->cs_cwc;
          ^~~
src/descrambler/cwc.c: In function ‘cwc_service_destroy’:
src/descrambler/cwc.c:1546:23: error: ‘cwc’ undeclared (first use in this function)
   pthread_mutex_lock(&cwc->cwc_mutex);
                       ^~~
cc1: all warnings being treated as errors
Makefile:640: recipe for target '/root/my_tvheadend/tvheadend/build.linux/src/descrambler/cwc.o' failed
make[3]: *** [/root/my_tvheadend/tvheadend/build.linux/src/descrambler/cwc.o] Error 1
make[3]: Leaving directory '/root/my_tvheadend/tvheadend'
Makefile:99: recipe for target 'ffmpeg_all' failed
make[2]: *** [ffmpeg_all] Error 2
make[2]: Leaving directory '/root/my_tvheadend/tvheadend'
debian/rules:11: recipe for target 'override_dh_auto_build' failed
make[1]: *** [override_dh_auto_build] Error 2
make[1]: Leaving directory '/root/my_tvheadend/tvheadend'
debian/rules:5: recipe for target 'build' failed
make: *** [build] Error 2
dpkg-buildpackage: error: debian/rules build gave error exit status 2

#17 Updated by Mark Clarkstone 4 months ago

Ivan debilas wrote:

snip.

Little tip, If you use the pre-tag [on the options bar for the reply/post box] it prevents redmine from thinking the code output in your message is markdown.

#18 Updated by Jaroslav Kysela 4 months ago

v4.3-257-gc143976

Also available in: Atom PDF