asterisk посылает cansel через 4 сек при вх. звонке

[serega_19]

asterisk посылает cansel через 4 сек при вх. звонке, при этой сигнализации звонок состоялся и длился 60 сек

[serega_19]

могу предоставить debug

[ded]

Солнце светит, но не греет.
Ночь становится длинее, а день короче.
Посылку пошлёшь, а она не приходит. А почта России говорит - не знаем где ваша посылка! Видите сколько у нас работы?

Могу тоже предоставить дебаг.

[Sfinx]

Ну и где там 60 секунд ? И где "звонок состоялся" ? Ткните пальцем, а то как бы мозг морщить в попытке угадать не сильная охота

[serega_19]

INVITE sip:xxxxxxxxxxxx@192.168.0.101:5161 SIP/2.0
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa;rport
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>
Contact: <sip:xxxxxxxxxxxx@xxxxxxxxxxxx:9060>
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;privacy=off;screen=no
Date: Thu, 01 Sep 2011 11:54:26 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces
Content-Type: application/sdp
Content-Length: 313

v=0
o=root 19930 19930 IN IP4 xxxxxxxxxxxx
s=session
c=IN IP4 xxxxxxxxxxxx
t=0 0
m=audio 16238 RTP/AVP 0 18 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

---
[2011-09-01 15:54:26] VERBOSE[5828] logger.c: -- Called xxxxxxxxxxxx
[2011-09-01 15:54:26] VERBOSE[19964] logger.c:
<--- SIP read from xxxxxxxxxxxx:5161 --->
SIP/2.0 100 Trying
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 INVITE
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa
Server: Linksys/SPA8000-5.1.10
Content-Length: 0


<------------->
[2011-09-01 15:54:26] VERBOSE[19964] logger.c: --- (8 headers 0 lines) ---
[2011-09-01 15:54:26] VERBOSE[19964] logger.c:
<--- SIP read from xxxxxxxxxxxx:5161 --->
SIP/2.0 180 Ringing
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>;tag=fef073b041381934i1
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 INVITE
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa
Server: Linksys/SPA8000-5.1.10
Remote-Party-ID: xxxxxxxxxxxx <sip:xxxxxxxxxxxx@sip5.uiscom.ru:9060>;screen=yes;party=called
Content-Length: 0

[2011-09-01 15:54:30] VERBOSE[5828] logger.c: Reliably Transmitting (NAT) to xxxxxxxxxxxx:5161:
CANCEL sip:xxxxxxxxxxxx@192.168.0.101:5161 SIP/2.0
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa;rport
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 CANCEL
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;privacy=off;screen=no
Content-Length: 0

[2011-09-01 15:54:30] VERBOSE[19964] logger.c:
<--- SIP read from xxxxxxxxxxxx:5161 --->
SIP/2.0 487 Request Terminated
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>;tag=fef073b041381934i1
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 INVITE
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa
Server: Linksys/SPA8000-5.1.10
Content-Length: 0

[2011-09-01 15:54:30] VERBOSE[19964] logger.c: Transmitting (NAT) to xxxxxxxxxxxx:5161:
ACK sip:xxxxxxxxxxxx@192.168.0.101:5161 SIP/2.0
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa;rport
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>;tag=fef073b041381934i1
Contact: <sip:xxxxxxxxxxxx@xxxxxxxxxxxx:9060>
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Remote-Party-ID: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;privacy=off;screen=no
Content-Length: 0

---
[2011-09-01 15:54:30] DEBUG[19964] chan_sip.c: Call to peer 'xxxxxxxxxxxx' removed from call limit 2
[2011-09-01 15:54:30] VERBOSE[19964] logger.c:
<--- SIP read from xxxxxxxxxxxx:5161 --->
SIP/2.0 200 OK
To: <sip:xxxxxxxxxxxx@192.168.0.101:5161>;tag=fef073b041381934i1
From: "xxxxxxxxxxxx" <sip:xxxxxxxxxxxx@xxxxxxxxxxxx>;tag=as2a48692e
Call-ID: 3b2453167fa433db028c11ec748bc50a@xxxxxxxxxxxx
CSeq: 102 CANCEL
Via: SIP/2.0/UDP xxxxxxxxxxxx:9060;branch=z9hG4bK6c7310aa
Server: Linksys/SPA8000-5.1.10
Content-Length: 0

[serega_19]

так в этом то и проблема, что по сигнализации звонка не было даже rtp нет в tcpdump

[Sfinx]

Так никто и не спорит что разговора не было. Откуда дровишки про 60 секунд ?

[serega_19]

есть запись разговора и cdr xxxxxxxxxxxxx xxxxxxxx 01-09-11 15:55:58 66 62 16

[Sfinx]

Кто-то у вас глючит, да и CDR не от этого разговора - INVITE пришел в 11:54:26 а ACK на CANCELв 15:54:30 - откуда 60 секунд взялись ? Есть такая штука uniqueid называется, так вот CDR надо по ней смотреть а не пальцем в небо.

[Sfinx]

Имеется в виду что судя по логу там на destination стоит call-limit = 2, т.е разговаривает себе чел, тут приходит звонок по второй линии - ну он и отбился. Так как CDR каличный, то ничего не видим - правьте CDR для начала, что бы была видна линия, потом можно и продолжить.