[OpenSIPS-Users] Mediaproxy: No Media and call ends with error

[Gavin Henry]

2009/6/8 Raúl Alexis Betancor Santana <rabs at dimension-virtual.com>:
> On Lunes, 8 de Junio de 2009 12:30:30 Gavin Henry escribió:
>> 2009/6/8 Raúl Alexis Betancor Santana <rabs at dimension-virtual.com>:
>> > On Lunes, 8 de Junio de 2009 10:01:58 Gavin Henry escribió:
>> >> We have the same problem on another server and can't resolve it.
>> >
>> > Just and advise:
>> >
>> > run it like this:
>> >
>> > strace media-proxy --no-fork
>> >
>> > And you will see what file it doesn't find.
>> >
>> > I suppose that it is a matter of perms over the filesystem.
>> >
>> > We rung mediaproxy 2.3.4 without any problem in our systems.
>>
>> That doesn't erally show anything.
>>
>> On one of our boxes that has conntrack built into the kernel and not a
>> module we are getting:
>
>
>>         mediaproxy.interfaces.system._conntrack.Error: No such file or
>> directory
>
> This means that it could not write to someplace ... probably /proc/net ..
> strace should tell you what file it is trying to access and not found.

I still can't see anything. I can see entries in /proc/net/ip_conntrack

I've even tested them running as root in case of perms. media* is
normally started via the debian init scripts.

I do see:

Jun  8 15:04:10 testsip opensips[22594]: ERROR:core:udp_send:
sendto(sock,0x81760a8,962,0,0xb5c1ad80,16): Operation not permitted(1)
Jun  8 15:04:10 testsip opensips[22594]: ERROR:tm:msg_send: udp_send failed
Jun  8 15:04:11 testsip opensips[22591]:
############################## NEW REQUEST RECEIVED
##############################
Jun  8 15:04:11 testsip opensips[22591]: request from X.X.X.1 [Mon Jun
 8 15:04:11 2009]: INVITE sip:1000 at xx.xx.co.uk -> <null>:
Jun  8 15:04:11 testsip opensips[22591]: request from X.X.X.1 [Mon Jun
 8 15:04:11 2009]:   Request: INVITE <null>
Jun  8 15:04:11 testsip opensips[22591]: From:
sip:1000 at xx.xx.co.uk;tag=dc3a1be4-a252-de11-85f7-001d09097b69
Jun  8 15:04:11 testsip opensips[22591]: To: sip:1001 at xx.xx.co.uk;tag=860b9f64
Jun  8 15:04:11 testsip opensips[22591]: SIP message-id: 406
Jun  8 15:04:11 testsip opensips[22591]: Call-id:
120719e4-a252-de11-85f7-001d09097b69 at siphost
Jun  8 15:04:11 testsip opensips[22591]: CSeq: 2
Jun  8 15:04:11 testsip opensips[22591]: Contact:
<sip:1001 at X.X.X.1:40296;rinstance=c79d4afddbdf4ab4>
Jun  8 15:04:11 testsip opensips[22591]:   Message Body: SIP/2.0 200
OK^M Via: SIP/2.0/UDP X.X.X.2;branch=z9hG4bK3655.43f299b4.0^M Via:
SIP/2.0/UDP X.X.X.1:5060;received=X.X.X.1;branch=z9hG4bK8eb732e4-a252-de11-85f7-001d09097b69;rport=5060^M
Record-Route: <sip:1001 at xx.xx.co.uk:5060;lr;ftag=dc3a1be4-a252-de11-85f7-001d09097b69>^M
Contact: <sip:1001 at X.X.X.1:40296;rinstance=c79d4afddbdf4ab4>^M To:
<sip:1001 at xx.xx.co.uk>;tag=860b9f64^M From: "Joe
Blogs"<sip:1000 at xx.xx.co.uk>;tag=dc3a1be4-a252-de11-85f7-001d09097b69^M
Call-ID: 120719e4-a252-de11-85f7-001d09097b69 at siphost^M CSeq: 2
INVITE^M Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY,
MESSAGE, SUBSCRIBE, INFO^M Content-Type: application/sdp^M User-Agent:
eyeBeam release 1102u stamp 52344^M Warning: 305 devnull "no common
codec for video"^M Content-Length: 212^M ^M v=0^M o=- 0 2 IN IP4
192.168.X.X^M s=CounterPath eyeBeam 1.5^M c=IN IP4 192.168.X.X^M t=0
0^M m=audio 38514 RTP/AVP 0 8 101^M a=fmtp:101 0
Jun  8 15:04:11 testsip media-dispatcher[27753]: debug: Issuing
"update" command to relay at X.X.X.2
Jun  8 15:04:11 testsip opensips[22595]:
############################## NEW REQUEST RECEIVED
##############################
Jun  8 15:04:11 testsip opensips[22595]: request from X.X.X.1 [Mon Jun
 8 15:04:11 2009]: ACK sip:1000 at xx.xx.co.uk ->
sip:1001 at X.X.X.1:40296;rinstance=c79d4afddbdf4ab4:
Jun  8 15:04:11 testsip opensips[22595]: request from X.X.X.1 [Mon Jun
 8 15:04:11 2009]:   Request: ACK
sip:1001 at X.X.X.1:40296;rinstance=c79d4afddbdf4ab4
Jun  8 15:04:11 testsip opensips[22595]: From:
sip:1000 at xx.xx.co.uk;tag=dc3a1be4-a252-de11-85f7-001d09097b69
Jun  8 15:04:11 testsip opensips[22595]: To: sip:1001 at xx.xx.co.uk;tag=860b9f64
Jun  8 15:04:11 testsip opensips[22595]: SIP message-id: 434
Jun  8 15:04:11 testsip opensips[22595]: Call-id:
120719e4-a252-de11-85f7-001d09097b69 at siphost
Jun  8 15:04:11 testsip opensips[22595]: CSeq: 2
Jun  8 15:04:11 testsip opensips[22595]: Contact: <sip:1000 at X.X.X.1>
Jun  8 15:04:11 testsip opensips[22595]:   Message Body: ACK
sip:1001 at X.X.X.1:40296;rinstance=c79d4afddbdf4ab4 SIP/2.0^M Route:
<sip:1001 at xx.xx.co.uk:5060;lr;ftag=dc3a1be4-a252-de11-85f7-001d09097b69>^M
CSeq: 2 ACK^M Via: SIP/2.0/UDP
X.X.X.1:5060;branch=z9hG4bKce65e0e8-a252-de11-85f7-001d09097b69;rport^M
From: "Joe Blogs"
<sip:1000 at xx.xx.co.uk>;tag=dc3a1be4-a252-de11-85f7-001d09097b69^M
Call-ID: 120719e4-a252-de11-85f7-001d09097b69 at siphost^M To:
<sip:1001 at xx.xx.co.uk>;tag=860b9f64^M Contact: <sip:1000 at X.X.X.1>^M
Proxy-Authorization: Digest username="1000", realm="xx.xx.co.uk",
nonce="4a2d1a710000020e92492c79c2dad9a7029ad73c0b05ac4a",
uri="sip:1001 at X.X.X.1:40296", algorithm=MD5,
response="3db0125fa5165aedb0676c7aa460ffa9"^M Content-Length: 0^M
Max-Forwards: 70^M ^M
Jun  8 15:04:11 testsip media-relay[27756]: Traceback (most recent call last):
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/twisted/python/log.py", line 36, in
callWithContext
Jun  8 15:04:11 testsip media-relay[27756]:     return
context.call({ILogContext: newCtx}, func, *args, **kw)
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/twisted/python/context.py", line 59,
in callWithContext
Jun  8 15:04:11 testsip media-relay[27756]:     return
self.currentContext().callWithContext(ctx, func, *args, **kw)
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/twisted/python/context.py", line 37,
in callWithContext
Jun  8 15:04:11 testsip media-relay[27756]:     return func(*args,**kw)
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/twisted/internet/epollreactor.py",
line 231, in _doReadOrWrite
Jun  8 15:04:11 testsip media-relay[27756]:     why = selectable.doRead()
Jun  8 15:04:11 testsip media-relay[27756]: --- <exception caught here> ---
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/twisted/internet/udp.py", line 126,
in doRead
Jun  8 15:04:11 testsip media-relay[27756]:
self.protocol.datagramReceived(data, addr)
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/mediaproxy/mediacontrol.py", line
121, in datagramReceived
Jun  8 15:04:11 testsip media-relay[27756]:     self.cb_func(host, port, data)
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/mediaproxy/mediacontrol.py", line
199, in got_data
Jun  8 15:04:11 testsip media-relay[27756]:
self.substream.check_create_conntrack()
Jun  8 15:04:11 testsip media-relay[27756]:   File
"/usr/lib/python2.5/site-packages/mediaproxy/mediacontrol.py", line
249, in check_create_conntrack
Jun  8 15:04:11 testsip media-relay[27756]:     self.forwarding_rule =
_conntrack.ForwardingRule(self.caller.remote, self.caller.local,
self.callee.remote, self.callee.local, self.stream.session.mark)
Jun  8 15:04:11 testsip media-relay[27756]:
mediaproxy.interfaces.system._conntrack.Error: No such file or
directory