Bug #3071
Incompatible sat2ip device ?
0%
Description
Can anyone tell me why my satip server wont work with tvheadend ?
2015-09-08 17:06:28.243 [ INFO] mpegts: 12272H in Thaicom - tuning on SAT>IP DVB-S Tuner #1 (192.168.1.29)
2015-09-08 17:06:28.243 [ DEBUG] satip: SAT>IP DVB-S Tuner #1 (192.168.1.29) - starting 12272H in Thaicom
2015-09-08 17:06:28.243 [ TRACE] satip: SAT>IP DVB-S Tuner #1 (192.168.1.29) - start
2015-09-08 17:06:28.243 [ INFO] subscription: 0003: "HTTP" subscribing on channel "DLTV1", weight: 100, adapter: "SAT>IP DVB-S Tuner #1 (192.168.1.29)", network: "Thaicom", mux: "12272H", provider: "TRUE", service: "DLTV1", profile="pass", hostname="183.88.52.56", client="Lavf/55.43.100"
2015-09-08 17:06:28.263 [ TRACE] satip: SAT>IP DVB-S Tuner #1 (192.168.1.29) - local RTP port 39932 RTCP port 39933
2015-09-08 17:06:28.263 [ TRACE] httpc: 0005: Connected to 192.168.1.29:554
2015-09-08 17:06:28.263 [ TRACE] satip: setup params - src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35
2015-09-08 17:06:28.263 [ TRACE] httpc: 0005: sending RTSP/1.0 cmd
2015-09-08 17:06:28.263 [ TRACE] httpc: 53 45 54 55 50 20 72 74 73 70 3A 2F 2F 31 39 32 SETUP rtsp://192
2015-09-08 17:06:28.263 [ TRACE] httpc: 2E 31 36 38 2E 31 2E 32 39 2F 3F 73 72 63 3D 31 .168.1.29/?src=1
2015-09-08 17:06:28.263 [ TRACE] httpc: 26 66 65 3D 31 26 66 72 65 71 3D 31 32 32 37 32 &fe=1&freq=12272
2015-09-08 17:06:28.263 [ TRACE] httpc: 26 73 72 3D 33 30 30 30 30 26 6D 73 79 73 3D 64 &sr=30000&msys=d
2015-09-08 17:06:28.263 [ TRACE] httpc: 76 62 73 26 6D 74 79 70 65 3D 71 70 73 6B 26 70 vbs&mtype=qpsk&p
2015-09-08 17:06:28.263 [ TRACE] httpc: 6F 6C 3D 68 26 66 65 63 3D 32 33 26 72 6F 3D 30 ol=h&fec=23&ro=0
2015-09-08 17:06:28.264 [ TRACE] httpc: 2E 33 35 20 52 54 53 50 2F 31 2E 30 0D 0A 54 72 .35 RTSP/1.0..Tr
2015-09-08 17:06:28.264 [ TRACE] httpc: 61 6E 73 70 6F 72 74 3A 20 52 54 50 2F 41 56 50 ansport: RTP/AVP
2015-09-08 17:06:28.264 [ TRACE] httpc: 3B 75 6E 69 63 61 73 74 3B 63 6C 69 65 6E 74 5F ;unicast;client_
2015-09-08 17:06:28.264 [ TRACE] httpc: 70 6F 72 74 3D 33 39 39 33 32 2D 33 39 39 33 33 port=39932-39933
2015-09-08 17:06:28.264 [ TRACE] httpc: 0D 0A 43 53 65 71 3A 20 31 0D 0A 0D 0A ..CSeq: 1....
2015-09-08 17:06:28.281 [ TRACE] httpc: 0005: recv 236
2015-09-08 17:06:28.281 [ TRACE] httpc: 0005: received RTSP/1.0 answer
2015-09-08 17:06:28.281 [ TRACE] httpc: 52 54 53 50 2F 31 2E 30 20 32 30 30 20 4F 4B 0D RTSP/1.0 200 OK.
2015-09-08 17:06:28.281 [ TRACE] httpc: 0A 44 61 74 65 3A 20 54 75 65 2C 20 53 65 70 20 .Date: Tue, Sep
2015-09-08 17:06:28.281 [ TRACE] httpc: 38 20 31 37 3A 30 36 3A 32 38 20 32 30 31 35 20 8 17:06:28 2015
2015-09-08 17:06:28.281 [ TRACE] httpc: 47 4D 54 0D 0A 43 73 65 71 3A 20 31 0D 0A 54 72 GMT..Cseq: 1..Tr
2015-09-08 17:06:28.281 [ TRACE] httpc: 61 6E 73 70 6F 72 74 3A 20 52 54 50 2F 41 56 50 ansport: RTP/AVP
2015-09-08 17:06:28.281 [ TRACE] httpc: 3B 75 6E 69 63 61 73 74 3B 64 65 73 74 69 6E 61 ;unicast;destina
2015-09-08 17:06:28.281 [ TRACE] httpc: 74 69 6F 6E 3D 31 39 32 2E 31 36 38 2E 31 2E 32 tion=192.168.1.2
2015-09-08 17:06:28.281 [ TRACE] httpc: 36 3B 73 6F 75 72 63 65 3D 31 39 32 2E 31 36 38 6;source=192.168
2015-09-08 17:06:28.281 [ TRACE] httpc: 2E 31 2E 32 39 3B 63 6C 69 65 6E 74 5F 70 6F 72 .1.29;client_por
2015-09-08 17:06:28.281 [ TRACE] httpc: 74 3D 33 39 39 33 32 2D 33 39 39 33 33 3B 73 65 t=39932-39933;se
2015-09-08 17:06:28.281 [ TRACE] httpc: 72 76 65 72 5F 70 6F 72 74 3D 35 35 30 30 2D 35 rver_port=5500-5
2015-09-08 17:06:28.281 [ TRACE] httpc: 35 30 31 0D 0A 53 65 73 73 69 6F 6E 3A 20 31 37 501..Session: 17
2015-09-08 17:06:28.281 [ TRACE] httpc: 31 34 36 33 36 39 31 35 3B 74 69 6D 65 6F 75 74 14636915;timeout
2015-09-08 17:06:28.281 [ TRACE] httpc: 3D 33 30 0D 0A 63 6F 6D 2E 73 65 73 2E 73 74 72 =30..com.ses.str
2015-09-08 17:06:28.281 [ TRACE] httpc: 65 61 6D 49 44 3A 20 31 0D 0A 0D 0A eamID: 1....
2015-09-08 17:06:28.281 [ TRACE] httpc: 0005: client flush 0
2015-09-08 17:06:28.281 [ TRACE] httpc: 0005: RTSP/1.0 answer 'RTSP/1.0 200 OK'
2015-09-08 17:06:28.281 [ DEBUG] satip: 192.168.1.29 #1 - new session 1714636915 stream id 1
2015-09-08 17:06:28.281 [ TRACE] satip: setup params - src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35
2015-09-08 17:06:28.281 [ TRACE] httpc: 0005: sending RTSP/1.0 cmd
2015-09-08 17:06:28.281 [ TRACE] httpc: 50 4C 41 59 20 72 74 73 70 3A 2F 2F 31 39 32 2E PLAY rtsp://192.
2015-09-08 17:06:28.281 [ TRACE] httpc: 31 36 38 2E 31 2E 32 39 2F 73 74 72 65 61 6D 3D 168.1.29/stream=
2015-09-08 17:06:28.281 [ TRACE] httpc: 31 3F 73 72 63 3D 31 26 66 65 3D 31 26 66 72 65 1?src=1&fe=1&fre
2015-09-08 17:06:28.281 [ TRACE] httpc: 71 3D 31 32 32 37 32 26 73 72 3D 33 30 30 30 30 q=12272&sr=30000
2015-09-08 17:06:28.281 [ TRACE] httpc: 26 6D 73 79 73 3D 64 76 62 73 26 6D 74 79 70 65 &msys=dvbs&mtype
2015-09-08 17:06:28.282 [ TRACE] httpc: 3D 71 70 73 6B 26 70 6F 6C 3D 68 26 66 65 63 3D =qpsk&pol=h&fec=
2015-09-08 17:06:28.282 [ TRACE] httpc: 32 33 26 72 6F 3D 30 2E 33 35 20 52 54 53 50 2F 23&ro=0.35 RTSP/
2015-09-08 17:06:28.282 [ TRACE] httpc: 31 2E 30 0D 0A 53 65 73 73 69 6F 6E 3A 20 31 37 1.0..Session: 17
2015-09-08 17:06:28.282 [ TRACE] httpc: 31 34 36 33 36 39 31 35 0D 0A 43 53 65 71 3A 20 14636915..CSeq:
2015-09-08 17:06:28.282 [ TRACE] httpc: 32 0D 0A 0D 0A 2....
2015-09-08 17:06:28.413 [ TRACE] httpc: 0005: recv 142
2015-09-08 17:06:28.413 [ TRACE] httpc: 0005: received RTSP/1.0 answer
2015-09-08 17:06:28.413 [ TRACE] httpc: 52 54 53 50 2F 31 2E 30 20 34 30 34 20 4E 6F 74 RTSP/1.0 404 Not
2015-09-08 17:06:28.413 [ TRACE] httpc: 20 46 6F 75 6E 64 0D 0A 44 61 74 65 3A 20 54 75 Found..Date: Tu
2015-09-08 17:06:28.413 [ TRACE] httpc: 65 2C 20 53 65 70 20 38 20 31 37 3A 30 36 3A 32 e, Sep 8 17:06:2
2015-09-08 17:06:28.413 [ TRACE] httpc: 38 20 32 30 31 35 20 47 4D 54 0D 0A 53 65 73 73 8 2015 GMT..Sess
2015-09-08 17:06:28.413 [ TRACE] httpc: 69 6F 6E 3A 20 31 37 31 34 36 33 36 39 31 35 0D ion: 1714636915.
2015-09-08 17:06:28.413 [ TRACE] httpc: 0A 43 73 65 71 3A 20 32 0D 0A 50 75 62 6C 69 63 .Cseq: 2..Public
2015-09-08 17:06:28.413 [ TRACE] httpc: 3A 20 4F 50 54 49 4F 4E 53 2C 20 44 45 53 43 52 : OPTIONS, DESCR
2015-09-08 17:06:28.413 [ TRACE] httpc: 49 42 45 2C 20 53 45 54 55 50 2C 20 50 4C 41 59 IBE, SETUP, PLAY
2015-09-08 17:06:28.413 [ TRACE] httpc: 2C 20 54 45 41 52 44 4F 57 4E 0D 0A 0D 0A , TEARDOWN....
2015-09-08 17:06:28.413 [ TRACE] httpc: 0005: client flush 0
2015-09-08 17:06:28.413 [ TRACE] httpc: 0005: RTSP/1.0 answer 'RTSP/1.0 404 Not Found'
2015-09-08 17:06:28.413 [ ERROR] satip: SAT>IP DVB-S Tuner #1 (192.168.1.29) - RTSP cmd error 7 (Unknown error 4294967289) [8-404]
2015-09-08 17:06:28.464 [ TRACE] httpc: 0005: sending RTSP/1.0 cmd
2015-09-08 17:06:28.464 [ TRACE] httpc: 54 45 41 52 44 4F 57 4E 20 72 74 73 70 3A 2F 2F TEARDOWN rtsp://
2015-09-08 17:06:28.464 [ TRACE] httpc: 31 39 32 2E 31 36 38 2E 31 2E 32 39 2F 73 74 72 192.168.1.29/str
2015-09-08 17:06:28.464 [ TRACE] httpc: 65 61 6D 3D 31 20 52 54 53 50 2F 31 2E 30 0D 0A eam=1 RTSP/1.0..
2015-09-08 17:06:28.464 [ TRACE] httpc: 53 65 73 73 69 6F 6E 3A 20 31 37 31 34 36 33 36 Session: 1714636
2015-09-08 17:06:28.464 [ TRACE] httpc: 39 31 35 0D 0A 43 53 65 71 3A 20 33 0D 0A 0D 0A 915..CSeq: 3....
2015-09-08 17:06:28.464 [ TRACE] httpc: 0005: recv -1
2015-09-08 17:06:28.466 [ TRACE] httpc: 0005: recv 85
2015-09-08 17:06:28.466 [ TRACE] httpc: 0005: received RTSP/1.0 answer
2015-09-08 17:06:28.466 [ TRACE] httpc: 52 54 53 50 2F 31 2E 30 20 32 30 30 20 4F 4B 0D RTSP/1.0 200 OK.
2015-09-08 17:06:28.466 [ TRACE] httpc: 0A 44 61 74 65 3A 20 54 75 65 2C 20 53 65 70 20 .Date: Tue, Sep
2015-09-08 17:06:28.466 [ TRACE] httpc: 38 20 31 37 3A 30 36 3A 32 38 20 32 30 31 35 20 8 17:06:28 2015
2015-09-08 17:06:28.466 [ TRACE] httpc: 47 4D 54 0D 0A 43 73 65 71 3A 20 33 0D 0A 53 65 GMT..Cseq: 3..Se
2015-09-08 17:06:28.466 [ TRACE] httpc: 73 73 69 6F 6E 3A 20 31 37 31 34 36 33 36 39 31 ssion: 171463691
2015-09-08 17:06:28.466 [ TRACE] httpc: 35 0D 0A 0D 0A 5....
2015-09-08 17:06:28.466 [ TRACE] httpc: 0005: client flush 0
2015-09-08 17:06:28.466 [ TRACE] httpc: 0005: RTSP/1.0 answer 'RTSP/1.0 200 OK'
2015-09-08 17:06:28.466 [ TRACE] httpc: 0005: client flush 0
2015-09-08 17:06:28.466 [ TRACE] httpc: 0005: Closed
2015-09-08 17:06:30.000 [WARNING] subscription: 0003: service instance is bad, reason: Tuning failed
2015-09-08 17:06:30.000 [ DEBUG] satip: SAT>IP DVB-S Tuner #1 (192.168.1.29) - stopping 12272H in Thaicom
2015-09-08 17:06:34.000 [ NOTICE] subscription: 0003: No input source available for subscription "HTTP" to channel "DLTV1"
2015-09-08 17:06:34.002 [WARNING] webui: Couldn't start streaming /stream/channelid/1772050980, Tuning failed
2015-09-08 17:06:34.003 [ INFO] subscription: 0003: "HTTP" unsubscribing from "DLTV1", hostname="183.88.52.56", client="Lavf/55.43.100"
History
Updated by Jaroslav Kysela about 9 years ago
Updated by Describe your SAT>IP server (model, vendor, firmware). Also, try to turn off the full play command in the tuner config and provide the whole httpc log, including the initial handshake.
Updated by danny skjodt about 9 years ago
Updated by Jaroslav Kysela wrote:
Describe your SAT>IP server (model, vendor, firmware). Also, try to turn off the full play command in the tuner config and provide the whole httpc log, including the initial handshake.
I think ive found the error, my sat2ip device a dreambox is missing DVBAPI 5.
Worked with minisatip on dm800se has kernel 3.2-dm800se.
But didnt from dm800 kernel 2.6.18-7.4-dm800.
minisatip binary just never complains about it on the dm800 so figured first the error was here.
So you can close it 
Updated by danny skjodt about 9 years ago
Verry strange tho.
[08/09 22:03:07.17967]: read RTSP (from handle 10 sock_id 5, len: 0, sid 0):
PLAY rtsp://192.168.1.29/stream=1?src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35 RTSP/1.0
Session: 1804289383
CSeq: 2
[08/09 22:03:07.17968]: starting init_hw 1
[08/09 22:03:07.17968]: detect_dvb_parameters (S)-> src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35
[08/09 22:03:07.17969]: detect_dvb_parameters (E) -> src=1, fe=1, freq=2147483647, fec=2, sr=30000000, pol=2, ro=0, msys=5, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL - apids=NULL - dpids=NULL x_pmt=NULL
[08/09 22:03:07.17969]: Setup stream 0 parameters, sock_id 5, handle 10
[08/09 22:03:07.17970]: copy_dvb_param start -> src=1, fe=1, freq=2147483647, fec=2, sr=30000000, pol=2, ro=0, msys=5, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL, apids=NULL, dpids=NULL x_pmt=NULL
[08/09 22:03:07.17970]: copy_dvb_parameters -> src=1, fe=1, freq=2147483647, fec=2 sr=30000000, pol=2, ro=0, msys=5, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL, apids=NULL, dpids=NULL x_pmt=NULL
[08/09 22:03:07.17970]: Play for stream 0, type 2, rsock 11, adapter -1, sock_id 5 handle 10
[08/09 22:03:07.17971]: get free adapter 0 - a0 => e:1 m:-1 sid_cnt:0 f:0 pol=0
[08/09 22:03:07.17971]: Got adapter 0 on socket 5
[08/09 22:03:07.17971]: set adapter 0 for stream 0 m:0 s:1
[08/09 22:03:07.17972]: setting DVB parameters for adapter 0 - master_sid 0 sid 0 old f:0
[08/09 22:03:07.17972]: deleting pids on adapter 0, sid -1, pids=NULL
[08/09 22:03:07.17972]: copy_dvb_param start -> src=0, fe=0, freq=0, fec=9, sr=0, pol=0, ro=3, msys=0, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL, apids=NULL, dpids=NULL x_pmt=NULL
[08/09 22:03:07.17973]: copy_dvb_parameters -> src=1, fe=1, freq=2147483647, fec=2 sr=30000000, pol=2, ro=0, msys=5, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL, apids=NULL, dpids=NULL x_pmt=NULL
[08/09 22:03:07.18103]: Frequency 2136883647 is not within range
[08/09 22:03:07.18104]: deleting pids on adapter 0, sid 0, pids=NULL
[08/09 22:03:07.18105]: reply -> 10 (192.168.1.26:34979) CL:0 :
RTSP/1.0 404 Not Found
Date: Tue, Sep 8 20:03:07 2015 GMT
Session: 1804289383
Cseq: 2
Public: OPTIONS, DESCRIBE, SETUP, PLAY, TEARDOWN
Updated by danny skjodt about 9 years ago
Working one :
[09/09 03:08:28.15911]: read RTSP (from handle 10 sock_id 5, len: 0, sid 0):
PLAY rtsp://192.168.1.28/stream=1?src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35 RTSP/1.0
Session: 1804289383
CSeq: 2
[09/09 03:08:28.15912]: starting init_hw 1
[09/09 03:08:28.15912]: detect_dvb_parameters (S)-> src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35
[09/09 03:08:28.15913]: detect_dvb_parameters (E) -> src=1, fe=1, freq=12272000, fec=2, sr=30000000, pol=2, ro=0, msys=5, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL - apids=NULL - dpids=NULL x_pmt=NULL
No idea how dm800 means PLAY rtsp://192.168.1.29/stream=1?src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35 gives :
[08/09 22:03:07.17968]: detect_dvb_parameters (S)-> src=1&fe=1&freq=12272&sr=30000&msys=dvbs&mtype=qpsk&pol=h&fec=23&ro=0.35
[08/09 22:03:07.17969]: detect_dvb_parameters (E) -> src=1, fe=1, freq=2147483647, fec=2, sr=30000000, pol=2, ro=0, msys=5, mtype=0, plts=2, bw=8000000, inv=2, pids=NULL - apids=NULL - dpids=NULL x_pmt=NULL
But seems related to minisatip or my device.
Updated by 
Updated by