Getting this to work with Asterisk PBX
652 views
Skip to first unread message
sam.be...@turnkeycorrections.com
May 23, 2012, 1:07:34 PM5/23/12
to doub...@googlegroups.com
Hey guys,
I host an Asterisk phone server and was wondering how I can shut off the video aspect of this product? I am able to register successfully with my server but I get a "SIP/2.0 488 Not acceptable here" error while attemping to place a call.
I believe this is because my server does not support the same video codec as the application. Also, is there a way to use GSM as the audio codec? Thanks for your help!
Here are the packets:
<--- SIP read from UDP:87.106.69.240:6060 --->
INVITE sip:71578...@asterisk.twvending.net SIP/2.0
Via: SIP/2.0/UDP 87.106.69.240:6060;branch=z9hG4bK-524287-1---0a728961069f2d78;rport
Via: SIP/2.0/TCP 68.65.34.156:51535;branch=z9hG4bK8J7PcqrqsI0Mqxfy6s3mUAXreEbW2JvH;rport=51535;received=68.65.34.156
Max-Forwards: 69
Contact: "Sam Bengtson"<sip:2...@87.106.69.240:6060;transport=udp;ws-src-ip=68.65.34.156;ws-src-port=51535>;+sip.ice
To: <sip:71578...@asterisk.twvending.net>
From: <sip:2...@asterisk.twvending.net>;tag=PGkNSWpRN0P732ECm3Wx
Call-ID: dc37ea1a-ebd4-f304-1915-655213fd66b6
CSeq: 52081 INVITE
Content-Type: application/sdp
Organization: Doubango Telecom
User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0
Authorization: Digest username="24",realm="asterisk",nonce="5b1916a6",uri="sip:71578...@asterisk.twvending.net",response="fe5428812239e9563b295596203a6c4a",algorithm=MD5
Content-Length: 3452
v=0
o=- 2291433634 1 IN IP4 127.0.0.1
s=webrtc (chrome 20.0.1127.0) - Doubango Telecom (sipML5 r000)
t=0 0
m=audio 32408 RTP/SAVPF 103 104 0 8 106 105 13 126
c=IN IP4 68.65.34.156
a=rtcp:36752 IN IP4 68.65.34.156
a=candidate:0 1 udp 2130706432 10.2.0.53 60568 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username NfoOErToFGpIbDPF password WIJ3W/ISqL4J3yOI7O9EdvB8 generation 0
a=candidate:0 2 udp 2130706432 10.2.0.53 60569 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username GQzhtqHvOmUwCqtR password 6zgqhsjil70ZzM+Wsv36M9VE generation 0
a=candidate:0 2 udp 1912602624 68.65.34.156 36752 typ srflx network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username GQzhtqHvOmUwCqtR password 6zgqhsjil70ZzM+Wsv36M9VE generation 0
a=candidate:0 1 udp 1912602624 68.65.34.156 32408 typ srflx network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username NfoOErToFGpIbDPF password WIJ3W/ISqL4J3yOI7O9EdvB8 generation 0
a=candidate:0 1 tcp 1694498816 10.2.0.53 57587 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username NfoOErToFGpIbDPF password WIJ3W/ISqL4J3yOI7O9EdvB8 generation 0
a=candidate:0 2 tcp 1694498816 10.2.0.53 57588 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username GQzhtqHvOmUwCqtR password 6zgqhsjil70ZzM+Wsv36M9VE generation 0
a=mid:audio
a=rtcp-mux
a=crypto:0 AES_CM_128_HMAC_SHA1_32 inline:ZDcCXqiSbBA4IxzYjxJ7SaFv5H4rXgiPtPLMRIIK
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:5UtU7JEOL54gX+hOwDn4VrGbvsqQAFUb4BITj9Mk
a=rtpmap:103 ISAC/16000
a=rtpmap:104 ISAC/32000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:106 CN/32000
a=rtpmap:105 CN/16000
a=rtpmap:13 CN/8000
a=rtpmap:126 telephone-event/8000
a=ssrc:2139712881 cname:oCUTV0DuIMr/+OBg
a=ssrc:2139712881 mslabel:RU1700tW93ch2SSma8CukitHnyude6c0lDY6
a=ssrc:2139712881 label:RU1700tW93ch2SSma8CukitHnyude6c0lDY60
a=sendrecv
m=video 19639 RTP/SAVPF 100 101 102
c=IN IP4 68.65.34.156
a=rtcp:43102 IN IP4 68.65.34.156
a=candidate:0 1 udp 2130706432 10.2.0.53 60570 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username /V1DqALNwqQ31Ev/ password gkRmr5PA7UBIt/I7xYEqdBmg generation 0
a=candidate:0 2 udp 2130706432 10.2.0.53 60571 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username b4ZU+jfjB+F6Xn3g password tbsrvizihej6xwvObfnaIBSH generation 0
a=candidate:0 1 udp 1912602624 68.65.34.156 19639 typ srflx network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username /V1DqALNwqQ31Ev/ password gkRmr5PA7UBIt/I7xYEqdBmg generation 0
a=candidate:0 2 udp 1912602624 68.65.34.156 43102 typ srflx network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username b4ZU+jfjB+F6Xn3g password tbsrvizihej6xwvObfnaIBSH generation 0
a=candidate:0 1 tcp 1694498816 10.2.0.53 57589 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username /V1DqALNwqQ31Ev/ password gkRmr5PA7UBIt/I7xYEqdBmg generation 0
a=candidate:0 2 tcp 1694498816 10.2.0.53 57590 typ host network_name {56C44119-8C15-4317-B1F9-1A3421F8F8A9} username b4ZU+jfjB+F6Xn3g password tbsrvizihej6xwvObfnaIBSH generation 0
a=mid:video
a=rtcp-mux
a=crypto:0 AES_CM_128_HMAC_SHA1_80 inline:WrKYLKXGkZcOz19aaYZyBRyx2tBU8UYx5RdkEEHe
a=rtpmap:100 VP8/90000
a=rtpmap:101 red/90000
a=rtpmap:102 ulpfec/90000
a=ssrc:2139712881 cname:oCUTV0DuIMr/+OBg
a=ssrc:2139712881 mslabel:RU1700tW93ch2SSma8CukitHnyude6c0lDY6
a=ssrc:2139712881 label:RU1700tW93ch2SSma8CukitHnyude6c0lDY60
a=sendrecv
<------------->
--- (14 headers 48 lines) ---
Sending to 87.106.69.240:6060 (NAT)
Using INVITE request as basis request - dc37ea1a-ebd4-f304-1915-655213fd66b6
Found peer '24' for '24' from 87.106.69.240:6060
== Using SIP RTP CoS mark 5
[May 23 17:02:46] WARNING[21780]: chan_sip.c:8664 process_sdp: Error in codec string 'F 103 104 0 8 106 105 13 126'
<--- Reliably Transmitting (NAT) to 87.106.69.240:6060 --->
SIP/2.0 488 Not acceptable here
Via: SIP/2.0/UDP 87.106.69.240:6060;branch=z9hG4bK-524287-1---0a728961069f2d78;received=87.106.69.240;rport=6060
Via: SIP/2.0/TCP 68.65.34.156:51535;branch=z9hG4bK8J7PcqrqsI0Mqxfy6s3mUAXreEbW2JvH;rport=51535;received=68.65.34.156
From: <sip:2...@asterisk.twvending.net>;tag=PGkNSWpRN0P732ECm3Wx
To: <sip:71578...@asterisk.twvending.net>;tag=as6ce2f720
Call-ID: dc37ea1a-ebd4-f304-1915-655213fd66b6
CSeq: 52081 INVITE
Server: Asterisk PBX SVN-branch-1.8-r331774
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0
<------------>
Mamadou
May 23, 2012, 1:59:41 PM5/23/12
to Doubango
"createAnswer" and "createOffer" from webkitPeerConnection have to
parameters: "has_audio" and "has_video" ...but chrome don't honor
these values.
As a workaround, you can remove the video from the SDP like this:
this.o_sdp_ro.remove_media("video");
To be added here: http://code.google.com/p/sipml5/source/browse/trunk/src/tinyMEDIA/src/tmedia_session_jsep.js#221
GSM codec is not supported. You can use G.711 which supported by
Asterisk.
On May 23, 7:07 pm, "sam.bengt...@turnkeycorrections.com"
> [May 23 17:02:46] WARNING[21780]: chan_sip.c:8664 process_sdp: *Error in
> codec string 'F 103 104 0 8 106 105 13 126'*
parameters: "has_audio" and "has_video" ...but chrome don't honor
these values.
As a workaround, you can remove the video from the SDP like this:
this.o_sdp_ro.remove_media("video");
To be added here: http://code.google.com/p/sipml5/source/browse/trunk/src/tinyMEDIA/src/tmedia_session_jsep.js#221
GSM codec is not supported. You can use G.711 which supported by
Asterisk.
On May 23, 7:07 pm, "sam.bengt...@turnkeycorrections.com"
<sam.bengt...@turnkeycorrections.com> wrote:
> Hey guys,
>
> I host an Asterisk phone server and was wondering how I can shut off the
> video aspect of this product? I am able to register successfully with my
> server but I get a "*SIP/2.0 488 Not acceptable here*" error while
> Hey guys,
>
> I host an Asterisk phone server and was wondering how I can shut off the
> video aspect of this product? I am able to register successfully with my
> attemping to place a call.
> I believe this is because my server does not support the same video codec
> as the application. Also, is there a way to use GSM as the audio codec?
> Thanks for your help!
>
> Here are the packets:
>
> <--- SIP read from UDP:87.106.69.240:6060 --->
> INVITE sip:7157814...@asterisk.twvending.net SIP/2.0
> I believe this is because my server does not support the same video codec
> as the application. Also, is there a way to use GSM as the audio codec?
> Thanks for your help!
>
> Here are the packets:
>
> <--- SIP read from UDP:87.106.69.240:6060 --->
> Via: SIP/2.0/UDP
> 87.106.69.240:6060;branch=z9hG4bK-524287-1---0a728961069f2d78;rport
> Via: SIP/2.0/TCP
> 68.65.34.156:51535;branch=z9hG4bK8J7PcqrqsI0Mqxfy6s3mUAXreEbW2JvH;rport=51535;received=68.65.34.156
> Max-Forwards: 69
> Contact: "Sam
> Bengtson"<sip:2...@87.106.69.240:6060;transport=udp;ws-src-ip=68.65.34.156;ws-src-port=51535>;+sip.ice
> To: <sip:7157814...@asterisk.twvending.net>
> 87.106.69.240:6060;branch=z9hG4bK-524287-1---0a728961069f2d78;rport
> Via: SIP/2.0/TCP
> 68.65.34.156:51535;branch=z9hG4bK8J7PcqrqsI0Mqxfy6s3mUAXreEbW2JvH;rport=51535;received=68.65.34.156
> Max-Forwards: 69
> Contact: "Sam
> Bengtson"<sip:2...@87.106.69.240:6060;transport=udp;ws-src-ip=68.65.34.156;ws-src-port=51535>;+sip.ice
> From: <sip:2...@asterisk.twvending.net>;tag=PGkNSWpRN0P732ECm3Wx
> Call-ID: dc37ea1a-ebd4-f304-1915-655213fd66b6
> CSeq: 52081 INVITE
> Content-Type: application/sdp
> Organization: Doubango Telecom
> User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0
> Authorization: Digest
> username="24",realm="asterisk",nonce="5b1916a6",uri="sip:7157814...@asterisk.twvending.net",response="fe5428812239e9563b295596203a6c4a",algorithm=MD5
> Call-ID: dc37ea1a-ebd4-f304-1915-655213fd66b6
> CSeq: 52081 INVITE
> Content-Type: application/sdp
> Organization: Doubango Telecom
> User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0
> Authorization: Digest
> codec string 'F 103 104 0 8 106 105 13 126'*
> <--- Reliably Transmitting (NAT) to 87.106.69.240:6060 --->
> SIP/2.0 488 Not acceptable here
> Via: SIP/2.0/UDP
> 87.106.69.240:6060;branch=z9hG4bK-524287-1---0a728961069f2d78;received=87.106.69.240;rport=6060
> Via: SIP/2.0/TCP
> 68.65.34.156:51535;branch=z9hG4bK8J7PcqrqsI0Mqxfy6s3mUAXreEbW2JvH;rport=51535;received=68.65.34.156
> From: <sip:2...@asterisk.twvending.net>;tag=PGkNSWpRN0P732ECm3Wx
> To: <sip:7157814...@asterisk.twvending.net>;tag=as6ce2f720
> SIP/2.0 488 Not acceptable here
> Via: SIP/2.0/UDP
> 87.106.69.240:6060;branch=z9hG4bK-524287-1---0a728961069f2d78;received=87.106.69.240;rport=6060
> Via: SIP/2.0/TCP
> 68.65.34.156:51535;branch=z9hG4bK8J7PcqrqsI0Mqxfy6s3mUAXreEbW2JvH;rport=51535;received=68.65.34.156
> From: <sip:2...@asterisk.twvending.net>;tag=PGkNSWpRN0P732ECm3Wx
Mamadou
May 23, 2012, 2:05:40 PM5/23/12
to Doubango
I was wrong. The "video" have to be removed from the local offer
instead of remote:
this.o_sdp_lo.remove_media("video");
Here: http://code.google.com/p/sipml5/source/browse/trunk/src/tinyMEDIA/src/tmedia_session_jsep.js#171
On May 23, 7:59 pm, Mamadou <diopmama...@doubango.org> wrote:
> "createAnswer" and "createOffer" from webkitPeerConnection have to
> parameters: "has_audio" and "has_video" ...but chrome don't honor
> these values.
> As a workaround, you can remove the video from the SDP like this:
> this.o_sdp_ro.remove_media("video");
> To be added here:http://code.google.com/p/sipml5/source/browse/trunk/src/tinyMEDIA/src...
instead of remote:
this.o_sdp_lo.remove_media("video");
Here: http://code.google.com/p/sipml5/source/browse/trunk/src/tinyMEDIA/src/tmedia_session_jsep.js#171
On May 23, 7:59 pm, Mamadou <diopmama...@doubango.org> wrote:
> "createAnswer" and "createOffer" from webkitPeerConnection have to
> parameters: "has_audio" and "has_video" ...but chrome don't honor
> these values.
> As a workaround, you can remove the video from the SDP like this:
> this.o_sdp_ro.remove_media("video");
Mamadou DIOP
Sep 26, 2012, 7:16:26 AM9/26/12
to doub...@googlegroups.com
Asterisk 1.8?
On Sep 26, 2012, at 10:33 AM, Assegaf Din wrote:
HI Mamadou,Unfortunately I got this too on my asterisk 1.8[Sep 26 13:24:00] WARNING[28898]: chan_sip.c:8937 process_sdp: Error in codec string 'F 103 104 0 8 106 105 13 126'I am trying with resiprote as ws proxy.I dont know whats wrong with this line why asterisk rejecting.a=group:BUNDLE audio videom=audio 21904 RTP/SAVPF 103 104 0 8 106 105 13 126I got my full console.log() javascript messageFrom: <sip:89799...@XXXX.com>;tag=s74ubFs0IMnhxDwbPZdA To: <sip:5...@XXXX.com> Contact: "8979993336"<sip:89799...@127.0.0.1:5060>;+sip.ice Call-ID: 1e8d0f02-6891-b749-0ccc-8512d330eb63 CSeq: 39161 INVITE Content-Type: application/sdp Content-Length: 2618 Max-Forwards: 70 Route: <sip:XXXX.com:5060;lr;transport=udp> User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0 Organization: Doubango Telecom v=0 o=- 3057818065 1 IN IP4 127.0.0.1 s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000) t=0 0 a=group:BUNDLE audio video m=audio 21904 RTP/SAVPF 103 104 0 8 106 105 13 126 c=IN IP4 118.96.5.183 a=rtcp:21904 IN IP4 118.96.5.183 a=candidate:2338155463 1 udp 2130714367 192.168.4.199 52609 typ host generation 0 a=candidate:2338155463 2 udp 2130714367 192.168.4.199 52609 typ host generation 0 a=candidate:3380112570 1 udp 2130714367 192.168.6.199 35109 typ host generation 0 a=candidate:3380112570 2 udp 2130714367 192.168.6.199 35109 typ host generation 0 a=candidate:3283969443 1 udp 2130714367 192.168.1.199 46068 typ host generation 0 a=candidate:3283969443 2 udp 2130714367 192.168.1.199 46068 typ host generation 0 a=candidate:1807062054 1 udp 2130714367 192.168.196.1 46560 typ host generation 0 a=candidate:1807062054 2 udp 2130714367 192.168.196.1 46560 typ host generation 0 a=candidate:1076198631 1 udp 2130714367 172.16.125.1 58818 typ host generation 0 a=candidate:1076198631 2 udp 2130714367 172.16.125.1 58818 typ host generation 0 a=candidate:169176435 1 udp 1912610559 118.96.5.183 21904 typ srflx generation 0 a=candidate:169176435 2 udp 1912610559 118.96.5.183 21904 typ srflx generation 0 a=candidate:3319699255 1 tcp 1694506751 192.168.4.199 55093 typ host generation 0 a=candidate:3319699255 2 tcp 1694506751 192.168.4.199 55093 typ host generation 0 a=candidate:2281410634 1 tcp 1694506751 192.168.6.199 41487 typ host generation 0 a=candidate:2281410634 2 tcp 1694506751 192.168.6.199 41487 typ host generation 0 a=candidate:2369689939 1 tcp 1694506751 192.168.1.199 42594 typ host generation 0 a=candidate:2369689939 2 tcp 1694506751 192.168.1.199 42594 typ host generation 0 a=candidate:624322774 1 tcp 1694506751 192.168.196.1 42637 typ host generation 0 a=candidate:624322774 2 tcp 1694506751 192.168.196.1 42637 typ host generation 0 a=candidate:245792791 1 tcp 1694506751 172.16.125.1 47570 typ host generation 0 a=candidate:245792791 2 tcp 1694506751 172.16.125.1 47570 typ host generation 0 a=ice-ufrag:YvEsxZpOUVI9lHAo a=ice-pwd:PZbjN8X9bAAnsd5KYb9FRy0z a=sendrecv a=mid:audio a=rtcp-mux a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:LjICEa32hY/bBObMiTXzTGlQs1u18+mVD0j/hFvm a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=ssrc:2950816435 cname:AhwgrmUftkH5B/yG a=ssrc:2950816435 mslabel:KezslOa9xqaKxe13rU428qCF5Qi3UhF8e4xP a=ssrc:2950816435 label:KezslOa9xqaKxe13rU428qCF5Qi3UhF8e4xP00 src/tinySAK/src/tsk_utils.js?svn=8:70SEND: INVITE sip:5...@XXXX.com SIP/2.0 Via: SIP/2.0/WS df7jal23ls0d.invalid;branch=z9hG4bKwtVeWT0H4Zr2JpKLHi7XErMW1F5oEvmp;rport From: <sip:89799...@XXXX.com>;tag=s74ubFs0IMnhxDwbPZdA To: <sip:5...@XXXX.com> Contact: "8979993336"<sip:89799...@df7jal23ls0d.invalid;transport=ws>;+sip.ice Call-ID: 1e8d0f02-6891-b749-0ccc-8512d330eb63 CSeq: 39161 INVITE Content-Type: application/sdp Content-Length: 2618 Max-Forwards: 70 Route: <sip:XXXX.com:5060;lr;transport=udp> User-Agent: IM-client/OMA1.0 sipML5/v0.0.0000.0 Organization: Doubango Telecom v=0 o=- 3057818065 1 IN IP4 127.0.0.1 s=webrtc (chrome 22.0.1189.0) - Doubango Telecom (sipML5 r000) t=0 0 a=group:BUNDLE audio video m=audio 21904 RTP/SAVPF 103 104 0 8 106 105 13 126 c=IN IP4 118.96.5.183 a=rtcp:21904 IN IP4 118.96.5.183 a=candidate:2338155463 1 udp 2130714367 192.168.4.199 52609 typ host generation 0 a=candidate:2338155463 2 udp 2130714367 192.168.4.199 52609 typ host generation 0 a=candidate:3380112570 1 udp 2130714367 192.168.6.199 35109 typ host generation 0 a=candidate:3380112570 2 udp 2130714367 192.168.6.199 35109 typ host generation 0 a=candidate:3283969443 1 udp 2130714367 192.168.1.199 46068 typ host generation 0 a=candidate:3283969443 2 udp 2130714367 192.168.1.199 46068 typ host generation 0 a=candidate:1807062054 1 udp 2130714367 192.168.196.1 46560 typ host generation 0 a=candidate:1807062054 2 udp 2130714367 192.168.196.1 46560 typ host generation 0 a=candidate:1076198631 1 udp 2130714367 172.16.125.1 58818 typ host generation 0 a=candidate:1076198631 2 udp 2130714367 172.16.125.1 58818 typ host generation 0 a=candidate:169176435 1 udp 1912610559 118.96.5.183 21904 typ srflx generation 0 a=candidate:169176435 2 udp 1912610559 118.96.5.183 21904 typ srflx generation 0 a=candidate:3319699255 1 tcp 1694506751 192.168.4.199 55093 typ host generation 0 a=candidate:3319699255 2 tcp 1694506751 192.168.4.199 55093 typ host generation 0 a=candidate:2281410634 1 tcp 1694506751 192.168.6.199 41487 typ host generation 0 a=candidate:2281410634 2 tcp 1694506751 192.168.6.199 41487 typ host generation 0 a=candidate:2369689939 1 tcp 1694506751 192.168.1.199 42594 typ host generation 0 a=candidate:2369689939 2 tcp 1694506751 192.168.1.199 42594 typ host generation 0 a=candidate:624322774 1 tcp 1694506751 192.168.196.1 42637 typ host generation 0 a=candidate:624322774 2 tcp 1694506751 192.168.196.1 42637 typ host generation 0 a=candidate:245792791 1 tcp 1694506751 172.16.125.1 47570 typ host generation 0 a=candidate:245792791 2 tcp 1694506751 172.16.125.1 47570 typ host generation 0 a=ice-ufrag:YvEsxZpOUVI9lHAo a=ice-pwd:PZbjN8X9bAAnsd5KYb9FRy0z a=sendrecv a=mid:audio a=rtcp-mux a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:LjICEa32hY/bBObMiTXzTGlQs1u18+mVD0j/hFvm a=rtpmap:103 ISAC/16000 a=rtpmap:104 ISAC/32000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:106 CN/32000 a=rtpmap:105 CN/16000 a=rtpmap:13 CN/8000 a=rtpmap:126 telephone-event/8000 a=ssrc:2950816435 cname:AhwgrmUftkH5B/yG a=ssrc:2950816435 mslabel:KezslOa9xqaKxe13rU428qCF5Qi3UhF8e4xP a=ssrc:2950816435 label:KezslOa9xqaKxe13rU428qCF5Qi3UhF8e4xP00
On Thursday, May 24, 2012 1:05:40 AM UTC+7, Mamadou wrote:I was wrong. The "video" have to be removed from the local offer
instead of remote:
this.o_sdp_lo.remove_media("video");
Here: http://code.google.com/p/sipml5/source/browse/trunk/src/tinyMEDIA/src/tmedia_session_jsep.js#171
--
Din Assegaf
Sep 26, 2012, 5:37:06 AM9/26/12
to doub...@googlegroups.com
Hi Mamadou,
I use Asterisk 1.8.10.0 , 64bit ubuntu 10.04, apt-get version
both I add res_srtp or not, still the same.
Maybe I will upgrade to the suggested svn version at sipml5 wiki.
WARNING[28898]: chan_sip.c:8937 process_sdp: Error in codec string 'F
103 104 0 8 106 105 13 126'
I use Asterisk 1.8.10.0 , 64bit ubuntu 10.04, apt-get version
both I add res_srtp or not, still the same.
Maybe I will upgrade to the suggested svn version at sipml5 wiki.
WARNING[28898]: chan_sip.c:8937 process_sdp: Error in codec string 'F
103 104 0 8 106 105 13 126'
Mamadou DIOP
Sep 26, 2012, 8:00:33 AM9/26/12
to doub...@googlegroups.com
This is not a maybe but a MUST.
You MUST update to the specified SVN revision and rebuild Asterisk as explained at http://code.google.com/p/sipml5/wiki/Asterisk
--
Din Assegaf
Sep 27, 2012, 3:28:47 AM9/27/12
to doub...@googlegroups.com
Hi Mamadou,
I build specific asterisk version with patched as defined in this wiki
http://code.google.com/p/sipml5/wiki/Asterisk
build success and asterisk running, registration ok.
look like the previous error gone,
but I got weird restart on asterisk server every making call to 555 (i
include logs at bottom of this email),
I just make simple extension dialplan like this,
exten => 555,1,Answer()
exten => 555,n,Wait(100)
exten => 555,n,Hangup
I am not sure whats going on, but can you recommended specific
operating system, version and any other specific server modified
configuration to have asterisk server at our own .
Thank you,
logs asterisk:
-- Registered SIP '8979993336' at 192.168.4.199:39848
> Saved useragent "IM-client/OMA1.0 sipML5-v1.0.89.0/" for peer
8979993336
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3592 __sip_xmit: Trying to
put 'SIP/2.0 200' onto WS socket destined for 192.168.4.199:5060
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:8397 sip_alloc: Allocating
new SIP dialog for 4a38628d72ff8314...@192.168.4.228:5060
- NOTIFY (No RTP)
[Sep 27 12:04:25] DEBUG[22409]: acl.c:979 ast_ouraddrfor: For
destination '192.168.4.199', our source address is '192.168.4.228'.
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3749 ast_sip_ouraddrfor:
Setting SIP_TRANSPORT_WS with address 192.168.4.228:5060
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3235 initialize_initreq:
Initializing initreq for method NOTIFY - callid
4a95ff8b30caa781...@192.168.4.228:5060
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto WS socket destined for 192.168.4.199:39848
[Sep 27 12:04:25] DEBUG[22322]: devicestate.c:342 _ast_device_state:
No provider found, checking channel drivers for SIP - 8979993336
[Sep 27 12:04:25] DEBUG[22322]: chan_sip.c:28948 sip_devicestate:
Checking device state for peer 8979993336
[Sep 27 12:04:25] DEBUG[22322]: devicestate.c:460 do_state_change:
Changing state for SIP/8979993336 - state 1 (Not in use)
[Sep 27 12:04:25] DEBUG[22322]: devicestate.c:440 devstate_event:
device 'SIP/8979993336' state '1'
[Sep 27 12:04:25] DEBUG[22356]: app_queue.c:1753 handle_statechange:
Device 'SIP/8979993336' changed to state '1' (Not in use) but we don't
care because they're not a member of any queue.
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 4a95ff8b30caa781...@192.168.4.228:5060
(Checking To) --From tag as5b509763 --To-tag
[Sep 27 12:04:25] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog
4a95ff8b30caa781...@192.168.4.228:5060
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:04:43] DEBUG[22328]: chan_sip.c:4104 __sip_autodestruct:
Re-scheduled destruction of SIP call
08bf3d0466e09b18...@192.168.4.228:5060
[Sep 27 12:04:43] DEBUG[22328]: chan_sip.c:4104 __sip_autodestruct:
Re-scheduled destruction of SIP call
1373cd3a6ff0a71b...@192.168.4.228:5060
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:04:57] DEBUG[22328]: chan_sip.c:4140 __sip_autodestruct:
Auto destroying SIP dialog '39857f9a-ba15-1aff-916a-db4a34062a6d'
[Sep 27 12:04:57] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog 39857f9a-ba15-1aff-916a-db4a34062a6d
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:4140 __sip_autodestruct:
Auto destroying SIP dialog
'08bf3d0466e09b18...@192.168.4.228:5060'
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog
08bf3d0466e09b18...@192.168.4.228:5060
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:4140 __sip_autodestruct:
Auto destroying SIP dialog
'1373cd3a6ff0a71b...@192.168.4.228:5060'
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog
1373cd3a6ff0a71b...@192.168.4.228:5060
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag
[Sep 27 12:05:22] DEBUG[22409]: logger.c:1291 ast_create_callid:
CALL_ID [C-00000000] created by thread.
[Sep 27 12:05:22] DEBUG[22409]: acl.c:979 ast_ouraddrfor: For
destination '192.168.4.199', our source address is '192.168.4.228'.
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:3749 ast_sip_ouraddrfor:
Setting SIP_TRANSPORT_WS with address 192.168.4.228:5060
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8397 sip_alloc: Allocating
new SIP dialog for 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc - INVITE (No
RTP)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:3592
__sip_xmit: Trying to put 'SIP/2.0 401' onto WS socket destined for
192.168.4.199:5060
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag as527937d6
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received ACK (6) - Command in SIP ACK
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:4284 __sip_ack:
Stopping retransmission on '0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc' of
Response 33002: Match Not Found
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:283
ast_rtp_instance_new: Using engine 'asterisk' for RTP instance
'0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:1226
ast_rtp_new: Allocated port 12728 for RTP instance '0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.3' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.3' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:292
ast_rtp_instance_new: RTP instance '0xb6305394' is setup and ready to
go
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:3368
ast_rtp_prop_set: Setup RTCP on RTP instance '0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.3' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.3' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
Setting NAT on RTP to Off
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP o=- 3663690601 1 IN IP4
127.0.0.1... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP s=webrtc (chrome
22.0.1189.0) - Doubango Telecom (sipML5 r000)... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP a=group:BUNDLE audio
video... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 103 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 104 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type
on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type
on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 106 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 105 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 13 based on m type
on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 126 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP c=IN IP4
118.96.5.183... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtcp:16691 IN IP4
118.96.5.183... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
1 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
2 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
1 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
2 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
1 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
2 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
1 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
2 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
1 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
2 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
1 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
2 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
1 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
2 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2281410634
1 tcp 1694506751 192.168.6.199 44084 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2281410634
2 tcp 1694506751 192.168.6.199 44084 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2369689939
1 tcp 1694506751 192.168.1.199 53884 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2369689939
2 tcp 1694506751 192.168.1.199 53884 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:624322774
1 tcp 1694506751 192.168.196.1 43922 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:624322774
2 tcp 1694506751 192.168.196.1 43922 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:245792791
1 tcp 1694506751 172.16.125.1 45880 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:245792791
2 tcp 1694506751 172.16.125.1 45880 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP
a=ice-ufrag:E2QfLv5qY3RQcNso... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP
a=ice-pwd:+j2qze5ASZ8i0eqXRUnK4adO... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=sendrecv... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=mid:audio...
UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtcp-mux...
UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: sip/sdp_crypto.c:105
sdp_crypto_setup: local_key64 7CpVnwlYjCam0hfiJrFqhqpNNu3Er6n+mw0791BM
len 40
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_srtp.c:494
ast_srtp_add_stream: Adding new policy for SSRC 491922708
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: sip/sdp_crypto.c:174
sdp_crypto_activate: SRTP policy activated
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:fBeoXWjPC0qYNljG9noXnLVKjbZf+/NeviFaNQl3 ... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 103 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:103
ISAC/16000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 104 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:104
ISAC/32000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:0
PCMU/8000... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:8
PCMA/8000... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 106 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:106
CN/32000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 105 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:105
CN/16000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:13 CN/8000...
OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:126
telephone-event/8000... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=ssrc:2121883236
cname:nw0AFoeQq5o5asJl... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=ssrc:2121883236
mslabel:qcFM3bTpusY5qdlH2vfyG6FCOIQcZ7YRZOlp... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=ssrc:2121883236
label:qcFM3bTpusY5qdlH2vfyG6FCOIQcZ7YRZOlp00... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:3413
ast_rtp_remote_address_set: Setting RTCP address on RTP instance
'0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 0 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 8 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 13 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 126 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:3334
ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance
'0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10312
process_sdp: We're settling with these formats: (ulaw|alaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:24790
handle_request_invite: Checking SIP call limits for device 8979993336
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:6344
update_call_counter: Updating call counter for incoming call
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: format_pref.c:338
ast_codec_choose: Could not find preferred codec - Going for the best
codec
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7585 sip_new:
*** Our native formats are (ulaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7586 sip_new:
*** Joint capabilities are (ulaw|alaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7587 sip_new:
*** Our capabilities are (gsm|ulaw|alaw|h263|vp8|testlaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7588 sip_new:
*** AST_CODEC_CHOOSE formats are ulaw
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7616 sip_new:
This channel will not be able to handle video.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:15760
build_route: build_route: Contact hop:
"abidin"<sip:89799...@df7jal23ls0d.invalid;transport=ws>;+sip.ice
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:25097
handle_request_invite: SIP/8979993336-00000000: New call is still
down.... Trying...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:3592
__sip_xmit: Trying to put 'SIP/2.0 100' onto WS socket destined for
192.168.4.199:5060
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:342 _ast_device_state:
No provider found, checking channel drivers for SIP - 8979993336
[Sep 27 12:05:22] DEBUG[22322]: chan_sip.c:28948 sip_devicestate:
Checking device state for peer 8979993336
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:460 do_state_change:
Changing state for SIP/8979993336 - state 1 (Not in use)
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:440 devstate_event:
device 'SIP/8979993336' state '1'
[Sep 27 12:05:22] DEBUG[22356]: app_queue.c:1753 handle_statechange:
Device 'SIP/8979993336' changed to state '1' (Not in use) but we don't
care because they're not a member of any queue.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: pbx.c:4410
pbx_extension_helper: Launching 'Answer'
-- Executing [555@default:1] Answer("SIP/8979993336-00000000", "")
in new stack
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:342 _ast_device_state:
No provider found, checking channel drivers for SIP - 8979993336
[Sep 27 12:05:22] DEBUG[22322]: chan_sip.c:28948 sip_devicestate:
Checking device state for peer 8979993336
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:460 do_state_change:
Changing state for SIP/8979993336 - state 1 (Not in use)
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:440 devstate_event:
device 'SIP/8979993336' state '1'
[Sep 27 12:05:22] DEBUG[22356]: app_queue.c:1753 handle_statechange:
Device 'SIP/8979993336' changed to state '1' (Not in use) but we don't
care because they're not a member of any queue.
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:6931
sip_answer: SIP answering channel: SIP/8979993336-00000000
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: res_rtp_asterisk.c:1563
ast_rtp_update_source: Setting the marker bit due to a source update
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:13152
transmit_response_with_sdp: Setting framing from config on incoming
call
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:12702 add_sdp:
** Our capability: (ulaw|alaw) Video flag: True Text flag: True
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:12703 add_sdp:
** Our prefcodec: (nothing)
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:12840 add_sdp:
-- Done with adding codecs to SDP
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:13043 add_sdp:
Done building SDP. Settling with this capability: (ulaw|alaw)
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:3592
__sip_xmit: Trying to put 'SIP/2.0 200' onto WS socket destined for
192.168.4.199:5060
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag as481988df
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received ACK (6) - Command in SIP ACK
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:4284 __sip_ack:
Stopping retransmission on '0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc' of
Response 33003: Match Not Found
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:23] DEBUG[22420][C-00000000]: channel.c:2974
__ast_answer: Didn't receive a media frame from
SIP/8979993336-00000000 within 500 ms of answering. Continuing anyway
[Sep 27 12:05:23] DEBUG[22420][C-00000000]: pbx.c:4410
pbx_extension_helper: Launching 'Wait'
-- Executing [555@default:2] Wait("SIP/8979993336-00000000",
"1000") in new stack
[Sep 27 12:05:38] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag as481988df
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP o=- 3663690601 2 IN IP4
127.0.0.1... UNSUPPORTED OR FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP s=webrtc (chrome
22.0.1189.0) - Doubango Telecom (sipML5 r000)... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP a=group:BUNDLE audio
video... UNSUPPORTED OR FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 103 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 104 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type
on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type
on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 106 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 105 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 13 based on m type
on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 126 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP c=IN IP4
118.96.5.183... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtcp:16691 IN IP4
118.96.5.183... UNSUPPORTED OR FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
1 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
2 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
1 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
2 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
1 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
2 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
1 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
2 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
1 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
2 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
1 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
2 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
1 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
2 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
user-ThinkCentre-A58e*CLI>
Disconnected from Asterisk server
Asterisk ended with exit status 134
Asterisk exited on signal 6.
Executing last minute cleanups
Asterisk cleanly ending (0).
I build specific asterisk version with patched as defined in this wiki
http://code.google.com/p/sipml5/wiki/Asterisk
build success and asterisk running, registration ok.
look like the previous error gone,
but I got weird restart on asterisk server every making call to 555 (i
include logs at bottom of this email),
I just make simple extension dialplan like this,
exten => 555,1,Answer()
exten => 555,n,Wait(100)
exten => 555,n,Hangup
I am not sure whats going on, but can you recommended specific
operating system, version and any other specific server modified
configuration to have asterisk server at our own .
Thank you,
logs asterisk:
-- Registered SIP '8979993336' at 192.168.4.199:39848
> Saved useragent "IM-client/OMA1.0 sipML5-v1.0.89.0/" for peer
8979993336
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3592 __sip_xmit: Trying to
put 'SIP/2.0 200' onto WS socket destined for 192.168.4.199:5060
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:8397 sip_alloc: Allocating
new SIP dialog for 4a38628d72ff8314...@192.168.4.228:5060
- NOTIFY (No RTP)
[Sep 27 12:04:25] DEBUG[22409]: acl.c:979 ast_ouraddrfor: For
destination '192.168.4.199', our source address is '192.168.4.228'.
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3749 ast_sip_ouraddrfor:
Setting SIP_TRANSPORT_WS with address 192.168.4.228:5060
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3235 initialize_initreq:
Initializing initreq for method NOTIFY - callid
4a95ff8b30caa781...@192.168.4.228:5060
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto WS socket destined for 192.168.4.199:39848
[Sep 27 12:04:25] DEBUG[22322]: devicestate.c:342 _ast_device_state:
No provider found, checking channel drivers for SIP - 8979993336
[Sep 27 12:04:25] DEBUG[22322]: chan_sip.c:28948 sip_devicestate:
Checking device state for peer 8979993336
[Sep 27 12:04:25] DEBUG[22322]: devicestate.c:460 do_state_change:
Changing state for SIP/8979993336 - state 1 (Not in use)
[Sep 27 12:04:25] DEBUG[22322]: devicestate.c:440 devstate_event:
device 'SIP/8979993336' state '1'
[Sep 27 12:04:25] DEBUG[22356]: app_queue.c:1753 handle_statechange:
Device 'SIP/8979993336' changed to state '1' (Not in use) but we don't
care because they're not a member of any queue.
[Sep 27 12:04:25] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 4a95ff8b30caa781...@192.168.4.228:5060
(Checking To) --From tag as5b509763 --To-tag
[Sep 27 12:04:25] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog
4a95ff8b30caa781...@192.168.4.228:5060
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 7 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:26] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 8 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:30] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 9 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:34] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 10 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:38] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans
id #3))
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:39847
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3856 retrans_pkt: ** SIP
timers: Rescheduling retransmission 11 to 4000 ms (t1 500 ms (Retrans
id #6))
[Sep 27 12:04:42] DEBUG[22328]: chan_sip.c:3592 __sip_xmit: Trying to
put 'NOTIFY sip:' onto UDP socket destined for 192.168.4.199:6061
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:04:43] DEBUG[22328]: chan_sip.c:4104 __sip_autodestruct:
Re-scheduled destruction of SIP call
08bf3d0466e09b18...@192.168.4.228:5060
[Sep 27 12:04:43] DEBUG[22328]: chan_sip.c:4104 __sip_autodestruct:
Re-scheduled destruction of SIP call
1373cd3a6ff0a71b...@192.168.4.228:5060
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:04:57] DEBUG[22328]: chan_sip.c:4140 __sip_autodestruct:
Auto destroying SIP dialog '39857f9a-ba15-1aff-916a-db4a34062a6d'
[Sep 27 12:04:57] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog 39857f9a-ba15-1aff-916a-db4a34062a6d
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
user-ThinkCentre-A58e*CLI>
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:4140 __sip_autodestruct:
Auto destroying SIP dialog
'08bf3d0466e09b18...@192.168.4.228:5060'
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog
08bf3d0466e09b18...@192.168.4.228:5060
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:4140 __sip_autodestruct:
Auto destroying SIP dialog
'1373cd3a6ff0a71b...@192.168.4.228:5060'
[Sep 27 12:05:15] DEBUG[22328]: chan_sip.c:6492 sip_destroy:
Destroying SIP dialog
1373cd3a6ff0a71b...@192.168.4.228:5060
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag
[Sep 27 12:05:22] DEBUG[22409]: logger.c:1291 ast_create_callid:
CALL_ID [C-00000000] created by thread.
[Sep 27 12:05:22] DEBUG[22409]: acl.c:979 ast_ouraddrfor: For
destination '192.168.4.199', our source address is '192.168.4.228'.
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:3749 ast_sip_ouraddrfor:
Setting SIP_TRANSPORT_WS with address 192.168.4.228:5060
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8397 sip_alloc: Allocating
new SIP dialog for 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc - INVITE (No
RTP)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:3592
__sip_xmit: Trying to put 'SIP/2.0 401' onto WS socket destined for
192.168.4.199:5060
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag as527937d6
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received ACK (6) - Command in SIP ACK
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:4284 __sip_ack:
Stopping retransmission on '0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc' of
Response 33002: Match Not Found
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:283
ast_rtp_instance_new: Using engine 'asterisk' for RTP instance
'0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:1226
ast_rtp_new: Allocated port 12728 for RTP instance '0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.3' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.3' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:292
ast_rtp_instance_new: RTP instance '0xb6305394' is setup and ready to
go
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:3368
ast_rtp_prop_set: Setup RTCP on RTP instance '0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.3' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.3' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
== Using SIP RTP CoS mark 5
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:5425 do_setnat:
Setting NAT on RTP to Off
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP o=- 3663690601 1 IN IP4
127.0.0.1... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP s=webrtc (chrome
22.0.1189.0) - Doubango Telecom (sipML5 r000)... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP a=group:BUNDLE audio
video... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 103 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 104 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type
on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type
on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 106 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 105 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 13 based on m type
on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 126 based on m
type on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP c=IN IP4
118.96.5.183... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtcp:16691 IN IP4
118.96.5.183... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
1 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
2 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
1 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
2 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
1 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
2 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
1 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
2 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
1 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
2 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
1 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
2 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
1 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
2 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2281410634
1 tcp 1694506751 192.168.6.199 44084 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2281410634
2 tcp 1694506751 192.168.6.199 44084 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2369689939
1 tcp 1694506751 192.168.1.199 53884 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2369689939
2 tcp 1694506751 192.168.1.199 53884 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:624322774
1 tcp 1694506751 192.168.196.1 43922 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:624322774
2 tcp 1694506751 192.168.196.1 43922 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:245792791
1 tcp 1694506751 172.16.125.1 45880 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:245792791
2 tcp 1694506751 172.16.125.1 45880 typ host generation 0... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP
a=ice-ufrag:E2QfLv5qY3RQcNso... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP
a=ice-pwd:+j2qze5ASZ8i0eqXRUnK4adO... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=sendrecv... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=mid:audio...
UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtcp-mux...
UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: sip/sdp_crypto.c:105
sdp_crypto_setup: local_key64 7CpVnwlYjCam0hfiJrFqhqpNNu3Er6n+mw0791BM
len 40
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_srtp.c:494
ast_srtp_add_stream: Adding new policy for SSRC 491922708
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: sip/sdp_crypto.c:174
sdp_crypto_activate: SRTP policy activated
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=crypto:1
AES_CM_128_HMAC_SHA1_80
inline:fBeoXWjPC0qYNljG9noXnLVKjbZf+/NeviFaNQl3 ... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 103 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:103
ISAC/16000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 104 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:104
ISAC/32000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:0
PCMU/8000... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:8
PCMA/8000... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 106 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:106
CN/32000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:641
ast_rtp_codecs_payloads_unset: Unsetting payload 105 on 0xb62421a0
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:105
CN/16000... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:13 CN/8000...
OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtpmap:126
telephone-event/8000... OK.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=ssrc:2121883236
cname:nw0AFoeQq5o5asJl... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=ssrc:2121883236
mslabel:qcFM3bTpusY5qdlH2vfyG6FCOIQcZ7YRZOlp... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=ssrc:2121883236
label:qcFM3bTpusY5qdlH2vfyG6FCOIQcZ7YRZOlp00... UNSUPPORTED OR FAILED.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:3413
ast_rtp_remote_address_set: Setting RTCP address on RTP instance
'0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 0 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 8 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 13 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: rtp_engine.c:515
ast_rtp_codecs_payloads_copy: Copying payload 126 from 0xb62421a0 to
0xb6305540
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: res_rtp_asterisk.c:3334
ast_rtp_prop_set: Ignoring duplicate RTCP property on RTP instance
'0xb6305394'
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:10312
process_sdp: We're settling with these formats: (ulaw|alaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:24790
handle_request_invite: Checking SIP call limits for device 8979993336
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:6344
update_call_counter: Updating call counter for incoming call
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.228' into...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.228' and port ''.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: format_pref.c:338
ast_codec_choose: Could not find preferred codec - Going for the best
codec
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7585 sip_new:
*** Our native formats are (ulaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7586 sip_new:
*** Joint capabilities are (ulaw|alaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7587 sip_new:
*** Our capabilities are (gsm|ulaw|alaw|h263|vp8|testlaw)
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7588 sip_new:
*** AST_CODEC_CHOOSE formats are ulaw
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:7616 sip_new:
This channel will not be able to handle video.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:15760
build_route: build_route: Contact hop:
"abidin"<sip:89799...@df7jal23ls0d.invalid;transport=ws>;+sip.ice
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:25097
handle_request_invite: SIP/8979993336-00000000: New call is still
down.... Trying...
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:3592
__sip_xmit: Trying to put 'SIP/2.0 100' onto WS socket destined for
192.168.4.199:5060
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:342 _ast_device_state:
No provider found, checking channel drivers for SIP - 8979993336
[Sep 27 12:05:22] DEBUG[22322]: chan_sip.c:28948 sip_devicestate:
Checking device state for peer 8979993336
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:460 do_state_change:
Changing state for SIP/8979993336 - state 1 (Not in use)
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:440 devstate_event:
device 'SIP/8979993336' state '1'
[Sep 27 12:05:22] DEBUG[22356]: app_queue.c:1753 handle_statechange:
Device 'SIP/8979993336' changed to state '1' (Not in use) but we don't
care because they're not a member of any queue.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: pbx.c:4410
pbx_extension_helper: Launching 'Answer'
-- Executing [555@default:1] Answer("SIP/8979993336-00000000", "")
in new stack
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:342 _ast_device_state:
No provider found, checking channel drivers for SIP - 8979993336
[Sep 27 12:05:22] DEBUG[22322]: chan_sip.c:28948 sip_devicestate:
Checking device state for peer 8979993336
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:460 do_state_change:
Changing state for SIP/8979993336 - state 1 (Not in use)
[Sep 27 12:05:22] DEBUG[22322]: devicestate.c:440 devstate_event:
device 'SIP/8979993336' state '1'
[Sep 27 12:05:22] DEBUG[22356]: app_queue.c:1753 handle_statechange:
Device 'SIP/8979993336' changed to state '1' (Not in use) but we don't
care because they're not a member of any queue.
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:6931
sip_answer: SIP answering channel: SIP/8979993336-00000000
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: res_rtp_asterisk.c:1563
ast_rtp_update_source: Setting the marker bit due to a source update
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:13152
transmit_response_with_sdp: Setting framing from config on incoming
call
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:12702 add_sdp:
** Our capability: (ulaw|alaw) Video flag: True Text flag: True
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:12703 add_sdp:
** Our prefcodec: (nothing)
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:12840 add_sdp:
-- Done with adding codecs to SDP
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:13043 add_sdp:
Done building SDP. Settling with this capability: (ulaw|alaw)
[Sep 27 12:05:22] DEBUG[22420][C-00000000]: chan_sip.c:3592
__sip_xmit: Trying to put 'SIP/2.0 200' onto WS socket destined for
192.168.4.199:5060
[Sep 27 12:05:22] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag as481988df
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received ACK (6) - Command in SIP ACK
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: chan_sip.c:4284 __sip_ack:
Stopping retransmission on '0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc' of
Response 33003: Match Not Found
[Sep 27 12:05:22] DEBUG[22409][C-00000000]: logger.c:1343
ast_callid_threadassoc_remove: CALL_ID [C-00000000] being removed from
thread.
[Sep 27 12:05:23] DEBUG[22420][C-00000000]: channel.c:2974
__ast_answer: Didn't receive a media frame from
SIP/8979993336-00000000 within 500 ms of answering. Continuing anyway
[Sep 27 12:05:23] DEBUG[22420][C-00000000]: pbx.c:4410
pbx_extension_helper: Launching 'Wait'
-- Executing [555@default:2] Wait("SIP/8979993336-00000000",
"1000") in new stack
[Sep 27 12:05:38] DEBUG[22409]: chan_sip.c:8766 find_call: = Looking
for Call ID: 0ad74af3-d4d7-dd6c-ffbd-7fa30c9a47bc (Checking From)
--From tag ubySC0CFP0FD3vWc5TJy --To-tag as481988df
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: logger.c:1321
ast_callid_threadassoc_add: CALL_ID [C-00000000] bound to thread.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:27504
handle_incoming: **** Received INVITE (5) - Command in SIP INVITE
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP v=0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP o=- 3663690601 2 IN IP4
127.0.0.1... UNSUPPORTED OR FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP s=webrtc (chrome
22.0.1189.0) - Doubango Telecom (sipML5 r000)... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP t=0 0... UNSUPPORTED OR
FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:9644
process_sdp: Processing session-level SDP a=group:BUNDLE audio
video... UNSUPPORTED OR FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 103 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 104 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 0 based on m type
on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 8 based on m type
on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 106 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 105 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 13 based on m type
on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: rtp_engine.c:557
ast_rtp_codecs_payloads_set_m_type: Setting payload 126 based on m
type on 0xb62421a0
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP c=IN IP4
118.96.5.183... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=rtcp:16691 IN IP4
118.96.5.183... UNSUPPORTED OR FAILED.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
1 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:2338155463
2 udp 2130714367 192.168.4.199 35076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
1 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.6.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.6.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3380112570
2 udp 2130714367 192.168.6.199 40694 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
1 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.1.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.1.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3283969443
2 udp 2130714367 192.168.1.199 56512 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
1 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.196.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.196.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1807062054
2 udp 2130714367 192.168.196.1 58499 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
1 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '172.16.125.1' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '172.16.125.1' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:1076198631
2 udp 2130714367 172.16.125.1 40076 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
1 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '118.96.5.183' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '118.96.5.183' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:169176435
2 udp 1912610559 118.96.5.183 16691 typ srflx generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
1 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '192.168.4.199' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '192.168.4.199' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:138
ast_sockaddr_split_hostport: Splitting '0' into...
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: netsock2.c:192
ast_sockaddr_split_hostport: ...host '0' and port ''.
[Sep 27 12:05:38] DEBUG[22409][C-00000000]: chan_sip.c:10050
process_sdp: Processing media-level (audio) SDP a=candidate:3319699255
2 tcp 1694506751 192.168.4.199 47475 typ host generation 0... OK.
user-ThinkCentre-A58e*CLI>
Disconnected from Asterisk server
Asterisk ended with exit status 134
Asterisk exited on signal 6.
Executing last minute cleanups
Asterisk cleanly ending (0).
Reply all
Reply to author
Forward
0 new messages