[AsteriskBrasil] Demora para Completar ligação
Rogério Santos
rogeriotbs em gmail.com
Sexta Novembro 2 13:45:20 -03 2018
Você pode ativar o debug no CLI com sip set debug peer ...
Assim consegue ver os detalhes do comportamento do peer
Rogerio Santos ■Mobile
On Fri, Nov 2, 2018, 13:39 Giliardy Arena <giliardy.arena em gmail.com> wrote:
> Boa tarde.
> Obrigado pela resposta, Rogerio.
>
> Sim , já testei como uma extensão simples e o cenário é o mesmo.
>
> No CLI eu só enxergo LOG quando a chamada é conectada.
> Não consigo ver nada diferente antes desse momento.
>
> Via tcpdump eu vejo as tentativas, mas não consigo identificar a causa do
> atraso através dele.
> Me chamou atenção a tentativa do Asterisk em todos os IPs do Call Manager,
> quando ele deveria se conectar diretamente ao que enviou a chamada.
>
> Você tem alguma sugestão que eu possa fazer no CLI para tentar enxergar a
> tentativa desde o recebimento do INVITE ?
>
> Atenciosamente,
> Giliardy Correia Arena.
>
>
>
>
> Em qui, 1 de nov de 2018 às 19:24, Giliardy Arena <
> giliardy.arena em gmail.com> escreveu:
>
>> Sim !
>>
>> Os ramais ficam no Cisco. Eu apenas vou ligar para um numero do Asterisk
>> que vai gravar as ligações.
>> Veja uma nova captura
>>
>> A troca de mensagens OPTION com os servidores que não possuem o ramal que
>> eu estou chamado do Cisco que parece estar atrasando.... Mas não sei como
>> resolver, pois já forcei apenas um servidor no sip.conf
>>
>>
>> 19:23:10.984078 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> INVITE sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:11.496042 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> INVITE sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:12.507249 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> INVITE sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:14.513145 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> INVITE sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:15.983468 ARP, Request who-has asterisk.ogmaster.local tell
>> cucmservice01, length 46
>> 19:23:15.983484 ARP, Reply asterisk.ogmaster.local is-at
>> 00:50:56:90:dc:d1 (oui Unknown), length 28
>> 19:23:18.524150 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> INVITE sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:19.220165 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:19.726828 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:20.739614 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:22.706629 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:22.755062 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:23.213088 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:24.220115 ARP, Request who-has asterisk.ogmaster.local tell
>> cucmservice02, length 46
>> 19:23:24.220130 ARP, Reply asterisk.ogmaster.local is-at
>> 00:50:56:90:dc:d1 (oui Unknown), length 28
>> 19:23:24.224829 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:24.292071 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:24.808252 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:25.810898 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:26.240672 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:26.533679 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> INVITE sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:26.762741 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:27.827149 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:29.292152 ARP, Request who-has asterisk.ogmaster.local tell
>> infocucmpub, length 46
>> 19:23:29.292168 ARP, Reply asterisk.ogmaster.local is-at
>> 00:50:56:90:dc:d1 (oui Unknown), length 28
>> 19:23:30.247068 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:30.769748 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:31.835377 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:34.259328 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:34.784241 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:35.845668 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:38.268704 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>> 19:23:38.797238 ARP, Request who-has 172.17.39.48 tell cucmservice02,
>> length 46
>> 19:23:38.989294 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 100 Trying
>> 19:23:38.989552 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 100 Trying
>> 19:23:38.989649 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 100 Trying
>> 19:23:38.989743 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 100 Trying
>> 19:23:38.989824 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 100 Trying
>> 19:23:38.989979 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990068 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990155 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990257 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990339 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990409 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990505 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990611 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990688 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990777 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990878 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.990994 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 100 Trying
>> 19:23:38.991069 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991130 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991218 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991311 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991460 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991545 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991636 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991723 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:38.991807 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 404 Not Found
>> 19:23:39.085356 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 180 Ringing
>> 19:23:39.797232 ARP, Request who-has 172.17.39.48 tell cucmservice02,
>> length 46
>> 19:23:40.768521 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> CANCEL sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:40.768819 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 487 Request Terminated
>> 19:23:40.768869 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>> SIP/2.0 200 OK
>> 19:23:40.771996 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>> ACK sip:2001 em 172.17.37.129:5060 SIP/2.0
>> 19:23:40.797266 ARP, Request who-has 172.17.39.48 tell cucmservice02,
>> length 46
>>
>> Atenciosamente,
>> Giliardy Correia Arena.
>>
>>
>>
>>
>> Em qui, 1 de nov de 2018 às 17:30, Giliardy Arena <
>> giliardy.arena em gmail.com> escreveu:
>>
>>> Oi Luiz.
>>> Estabeleci um SIP entre o Call Manager e o Asterisk.
>>> O Call Manager possui um Publisher (39.41) e os Subscribers (39.42 e
>>> 39.43), onde ficam os telefones registrados.
>>>
>>> Já testei tanto deixando todos os IPs possíveis do Call Manager, quanto
>>> apenas a referente ao registro do meu telefone no Call Manager(39.42) e a
>>> demora é a mesma.
>>>
>>> ;[callman01]
>>> ;type=friend
>>> ;context=ramais
>>> ;host=172.17.39.41
>>> ;disallow=all
>>> ;allow=ulaw
>>> ;allow=alaw
>>> ;nat=no
>>> ;canreinvite=yes
>>> ;qualify=yes
>>>
>>> [callman02]
>>> type=friend
>>> context=ramais
>>> host=172.17.39.42
>>> disallow=all
>>> allow=ulaw
>>> allow=alaw
>>> nat=no
>>> canreinvite=yes
>>> qualify=yes
>>>
>>> ;[callman03]
>>> ;type=friend
>>> ;context=ramais
>>> ;host=172.17.39.43
>>> ;disallow=all
>>> ;allow=ulaw
>>> ;allow=alaw
>>> ;nat=no
>>> ;canreinvite=yes
>>> ;qualify=yes
>>>
>>>
>>>
>>> Do lado do Call Manager está tudo configurado e eles estão falando UDP.
>>>
>>>
>>>
>>>
>>> No lado do Asterisk , não consegui alguma captura especifica, mas peguei
>>> via TCPDUMP que ele parece tentar todos antes de efetivamente fechar com o
>>> primeiro , embora já tenha recebido INVITE do correto.
>>>
>>>
>>>
>>> tcpdump -i ens192 dst 172.17.37.129 and src 172.17.39.41 or 172.17.39.42
>>> or 172.17.39.43
>>>
>>>
>>> 16:47:31.740674 IP *cucmservice01.sip* > asterisk.ogmaster.local.sip:
>>> SIP: INVITE sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:32.254307 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> INVITE sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:33.258050 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> INVITE sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:35.272582 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> INVITE sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:38.225049 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:38.740848 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:39.282208 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> INVITE sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:39.751717 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:41.754129 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:43.224610 ARP, Request who-has asterisk.ogmaster.local tell
>>> infocucmpub, length 46
>>> 16:47:45.768670 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:46.055483 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:46.560533 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:47.292581 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> INVITE sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:47.572900 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:49.587485 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:49.780979 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:51.054865 ARP, Request who-has asterisk.ogmaster.local tell
>>> cucmservice02, length 46
>>> 16:47:52.292278 ARP, Request who-has asterisk.ogmaster.local tell
>>> cucmservice01, length 46
>>> 16:47:53.596301 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:53.785687 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:57.607030 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:47:59.754553 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> ACK sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:59.755067 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> ACK sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:47:59.756284 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> ACK sip:2005 em 172.17.37.129:5060 SIP/2.0
>>> 16:48:00.535923 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> OPTIONS sip:172.17.37.129:5060 SIP/2.0
>>> 16:48:02.126054 IP infocucmpub.sip > asterisk.ogmaster.local.sip: SIP:
>>> SIP/2.0 200 OK
>>> 16:48:02.220213 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP:
>>> SIP/2.0 200 OK
>>> 16:48:02.220484 IP cucmservice02.sip > asterisk.ogmaster.local.sip: SIP:
>>> SIP/2.0 200 OK
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>> tcpdump -i ens192 src 172.17.37.129 and dst 172.17.39.41 or 172.17.39.42
>>> or 172.17.39.43
>>>
>>>
>>> 16:47:59.749555 IP asterisk.ogmaster.local.sip > *cucmservice01.sip*:
>>> SIP: SIP/2.0 100 Trying
>>> 16:47:59.749932 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 100 Trying
>>> 16:47:59.750055 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 100 Trying
>>> 16:47:59.750181 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 100 Trying
>>> 16:47:59.750348 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.750472 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.750514 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 200 OK
>>> 16:47:59.750797 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 100 Trying
>>> 16:47:59.750935 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 200 OK
>>> 16:47:59.751084 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.751193 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.751293 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.751487 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.751608 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.751761 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 100 Trying
>>> 16:47:59.751864 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 200 OK
>>> 16:47:59.751998 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.752116 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.752230 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.752343 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.752458 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:47:59.752576 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:48:00.536313 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 404 Not Found
>>> 16:48:02.124006 IP asterisk.ogmaster.local.sip > infocucmpub.sip: SIP:
>>> OPTIONS sip:172.17.39.41 SIP/2.0
>>> 16:48:02.218575 IP asterisk.ogmaster.local.sip > cucmservice02.sip: SIP:
>>> OPTIONS sip:172.17.39.43 SIP/2.0
>>> 16:48:02.218761 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> OPTIONS sip:172.17.39.42 SIP/2.0
>>> 16:48:02.589632 IP asterisk.ogmaster.local.sip > cucmservice01.sip: SIP:
>>> SIP/2.0 200 OK
>>>
>>>
>>>
>>>
>>>
>>> Testei alguns Debugs que fui pesquisando na internet mas não consegui
>>> compreender muito bem....
>>>
>>>
>>>
>>>
>>>
>>> [Oct 31 15:35:20] DEBUG[31072] chan_sip.c: = Looking for Call ID:
>>> b4b7cf80-bd91f5e4-3b3ad1-2a2711ac em 172.17.39.42 (Checking From) --From
>>> tag 1146601895 --To-tag
>>> [Oct 31 15:35:20] DEBUG[31072] acl.c: For destination '172.17.39.42',
>>> our source address is '172.17.37.129'.
>>> [Oct 31 15:35:20] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:35:20] DEBUG[31072] netsock2.c: Splitting '172.17.39.42:5060'
>>> into...
>>> [Oct 31 15:35:20] DEBUG[31072] netsock2.c: ...host '172.17.39.42' and
>>> port '5060'.
>>> [Oct 31 15:35:20] DEBUG[31072] chan_sip.c: Allocating new SIP dialog for
>>> b4b7cf80-bd91f5e4-3b3ad1-2a2711ac em 172.17.39.42 - OPTIONS (No RTP)
>>> [Oct 31 15:35:20] DEBUG[31072] chan_sip.c: **** Received OPTIONS (3) -
>>> Command in SIP OPTIONS
>>> [Oct 31 15:35:20] DEBUG[31072] netsock2.c: Splitting '172.17.37.129:5060'
>>> into...
>>> [Oct 31 15:35:20] DEBUG[31072] netsock2.c: ...host '172.17.37.129' and
>>> port ''.
>>> [Oct 31 15:35:20] DEBUG[31072] netsock2.c: Splitting '172.17.39.42'
>>> into...
>>> [Oct 31 15:35:20] DEBUG[31072] netsock2.c: ...host '172.17.39.42' and
>>> port ''.
>>> [Oct 31 15:35:20] DEBUG[31072] chan_sip.c: Trying to put 'SIP/2.0 404'
>>> onto UDP socket destined for 172.17.39.42:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Allocating new SIP dialog for
>>> 7eeb423d62baf89b2376864b55f025a9@[fe80::a0e0:69c4:bc8b:b417]:5060 -
>>> OPTIONS (No RTP)
>>> [Oct 31 15:35:21] DEBUG[31072] acl.c: For destination '172.17.39.43',
>>> our source address is '172.17.37.129'.
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: SIP call-id changed from
>>> '7eeb423d62baf89b2376864b55f025a9@[fe80::a0e0:69c4:bc8b:b417]:5060' to '
>>> 68e59f75777e9a5c455eac993191add0 em 172.17.37.129:5060'
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Initializing initreq for
>>> method OPTIONS - callid
>>> 68e59f75777e9a5c455eac993191add0 em 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Trying to put 'OPTIONS sip'
>>> onto UDP socket destined for 172.17.39.43:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Allocating new SIP dialog for
>>> 2b73bb0d2c3469fa0780743f3270ca4f@[fe80::a0e0:69c4:bc8b:b417]:5060 -
>>> OPTIONS (No RTP)
>>> [Oct 31 15:35:21] DEBUG[31072] acl.c: For destination '172.17.39.42',
>>> our source address is '172.17.37.129'.
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: SIP call-id changed from
>>> '2b73bb0d2c3469fa0780743f3270ca4f@[fe80::a0e0:69c4:bc8b:b417]:5060' to '
>>> 16c1f43e5149fd8d1e2f27cc630f3ee8 em 172.17.37.129:5060'
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Initializing initreq for
>>> method OPTIONS - callid
>>> 16c1f43e5149fd8d1e2f27cc630f3ee8 em 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Trying to put 'OPTIONS sip'
>>> onto UDP socket destined for 172.17.39.42:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Allocating new SIP dialog for
>>> 3c48a6e96480adda0d8af61a4d498fb7@[fe80::a0e0:69c4:bc8b:b417]:5060 -
>>> OPTIONS (No RTP)
>>> [Oct 31 15:35:21] DEBUG[31072] acl.c: For destination '172.17.39.41',
>>> our source address is '172.17.37.129'.
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: SIP call-id changed from
>>> '3c48a6e96480adda0d8af61a4d498fb7@[fe80::a0e0:69c4:bc8b:b417]:5060' to '
>>> 447c59563b0c41e72d5fd888396c6d5d em 172.17.37.129:5060'
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Initializing initreq for
>>> method OPTIONS - callid
>>> 447c59563b0c41e72d5fd888396c6d5d em 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Trying to put 'OPTIONS sip'
>>> onto UDP socket destined for 172.17.39.41:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: = Looking for Call ID:
>>> 68e59f75777e9a5c455eac993191add0 em 172.17.37.129:5060 (Checking To)
>>> --From tag as2ee346e2 --To-tag 348178859
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Stopping retransmission on '
>>> 68e59f75777e9a5c455eac993191add0 em 172.17.37.129:5060' of Request 102:
>>> Match Found
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: = Looking for Call ID:
>>> 16c1f43e5149fd8d1e2f27cc630f3ee8 em 172.17.37.129:5060 (Checking To)
>>> --From tag as138ca155 --To-tag 802041871
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Stopping retransmission on '
>>> 16c1f43e5149fd8d1e2f27cc630f3ee8 em 172.17.37.129:5060' of Request 102:
>>> Match Found
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Destroying SIP dialog
>>> 68e59f75777e9a5c455eac993191add0 em 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Destroying SIP dialog
>>> 16c1f43e5149fd8d1e2f27cc630f3ee8 em 172.17.37.129:5060
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: = Looking for Call ID:
>>> 447c59563b0c41e72d5fd888396c6d5d em 172.17.37.129:5060 (Checking To)
>>> --From tag as34b82738 --To-tag 605276003
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Stopping retransmission on '
>>> 447c59563b0c41e72d5fd888396c6d5d em 172.17.37.129:5060' of Request 102:
>>> Match Found
>>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Destroying SIP dialog
>>> 447c59563b0c41e72d5fd888396c6d5d em 172.17.37.129:5060
>>> [Oct 31 15:35:36] DEBUG[31072] chan_sip.c: Auto destroying SIP dialog '
>>> ab2e6780-bd91f5d4-1f9f50-2b2711ac em 172.17.39.43'
>>> [Oct 31 15:35:36] DEBUG[31072] chan_sip.c: Destroying SIP dialog
>>> ab2e6780-bd91f5d4-1f9f50-2b2711ac em 172.17.39.43
>>> [Oct 31 15:35:43] DEBUG[31072] chan_sip.c: Auto destroying SIP dialog '
>>> af5a8500-bd91f5db-1b63e6-292711ac em 172.17.39.41'
>>> [Oct 31 15:35:43] DEBUG[31072] chan_sip.c: Destroying SIP dialog
>>> af5a8500-bd91f5db-1b63e6-292711ac em 172.17.39.41
>>> [Oct 31 15:35:52] DEBUG[31072] chan_sip.c: Auto destroying SIP dialog '
>>> b4b7cf80-bd91f5e4-3b3ad1-2a2711ac em 172.17.39.42'
>>> [Oct 31 15:35:52] DEBUG[31072] chan_sip.c: Destroying SIP dialog
>>> b4b7cf80-bd91f5e4-3b3ad1-2a2711ac em 172.17.39.42
>>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: = Looking for Call ID:
>>> cef1ad80-bd91f610-1f9f6a-2b2711ac em 172.17.39.43 (Checking From) --From
>>> tag 1522038610 --To-tag
>>> [Oct 31 15:36:04] DEBUG[31072] acl.c: For destination '172.17.39.43',
>>> our source address is '172.17.37.129'.
>>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:36:04] DEBUG[31072] netsock2.c: Splitting '172.17.39.43:5060'
>>> into...
>>> [Oct 31 15:36:04] DEBUG[31072] netsock2.c: ...host '172.17.39.43' and
>>> port '5060'.
>>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: Allocating new SIP dialog for
>>> cef1ad80-bd91f610-1f9f6a-2b2711ac em 172.17.39.43 - OPTIONS (No RTP)
>>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: **** Received OPTIONS (3) -
>>> Command in SIP OPTIONS
>>> [Oct 31 15:36:04] DEBUG[31072] netsock2.c: Splitting '172.17.37.129:5060'
>>> into...
>>> [Oct 31 15:36:04] DEBUG[31072] netsock2.c: ...host '172.17.37.129' and
>>> port ''.
>>> [Oct 31 15:36:04] DEBUG[31072] netsock2.c: Splitting '172.17.39.43'
>>> into...
>>> [Oct 31 15:36:04] DEBUG[31072] netsock2.c: ...host '172.17.39.43' and
>>> port ''.
>>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: Trying to put 'SIP/2.0 404'
>>> onto UDP socket destined for 172.17.39.43:5060
>>> [Oct 31 15:36:12] DEBUG[31072] chan_sip.c: = Looking for Call ID:
>>> d3b66180-bd91f618-1b63f9-292711ac em 172.17.39.41 (Checking From) --From
>>> tag 639004019 --To-tag
>>> [Oct 31 15:36:12] DEBUG[31072] acl.c: For destination '172.17.39.41',
>>> our source address is '172.17.37.129'.
>>> [Oct 31 15:36:12] DEBUG[31072] chan_sip.c: Setting AST_TRANSPORT_UDP
>>> with address 172.17.37.129:5060
>>> [Oct 31 15:36:12] DEBUG[31072] netsock2.c: Splitting '172.17.39.41:5060'
>>> into...
>>> [Oct 31 15:36:12] DEBUG[31072] netsock2.c: ...host '172.17.39.41' and
>>> port '5060'.
>>> [Oct 31 15:36:12] DEBUG[31072] chan_sip.c: Allocating new SIP dialog for
>>> d3b66180-bd91f618-1b63f9-292711ac em 172.17.39.41 - OPTIONS (No RTP)
>>> [Oct 31 15:36:12] DEBUG[31072] chan_sip.c: **** Received OPTIONS (3) -
>>> Command in SIP OPTIONS
>>> [Oct 31 15:36:12] DEBUG[31072] netsock2.c: Splitting '172.17.37.129:5060'
>>> into...
>>> [Oct 31 15:36:12] DEBUG[31072] netsock2.c: ...host '172.17.37.129' and
>>> port ''.
>>> [Oct 31 15:36:12] DEBUG[31072] netsock2.c: Splitting '172.17.39.41'
>>> into...
>>> [Oct 31 15:36:12] DEBUG[31072] netsock2.c: ...host '172.17.39.41' and
>>> port ''.
>>> [Oct 31 15:36:12] DEBUG[31072] chan_sip.c: Trying to put 'SIP/2.0 404'
>>> onto UDP socket destined for 172.17.39.41:5060
>>>
>>>
>>>
>>>
>>> Atenciosamente,
>>> Giliardy Correia Arena.
>>>
>>>
>>>
>>>
>>> Em qui, 1 de nov de 2018 às 15:05, Giliardy Arena <
>>> giliardy.arena em gmail.com> escreveu:
>>>
>>>> Olá pessoal !
>>>> Alguma ajuda ? Alguma dica ?
>>>>
>>>> Obrigado
>>>>
>>>>
>>>> Atenciosamente,
>>>> Giliardy Correia Arena.
>>>>
>>>>
>>>>
>>>>
>>>> Em qua, 31 de out de 2018 às 10:58, Giliardy Arena <
>>>> giliardy.arena em gmail.com> escreveu:
>>>>
>>>>> Olá , bom dia.
>>>>>
>>>>> Alguém sugere alguma forma de eu rastrear a ligação desde a chegada da
>>>>> requisicao SIP no servidor Asterisk , para entender o motivo de demorar
>>>>> muito para conectar? Algum debug específico, um trace , um log...
>>>>>
>>>>> Obrigado
>>>>>
>>>>> Em ter, 30 de out de 2018 20:22, Giliardy Arena <
>>>>> giliardy.arena em gmail.com> escreveu:
>>>>>
>>>>>> Sylvio
>>>>>>
>>>>>> O waitforsilence é para identificar se não tiver mais conversação e
>>>>>> encerrar a ligação.
>>>>>> Para evitar ficar alguma chamada presa gravando eternamente.
>>>>>>
>>>>>>
>>>>>> Atenciosamente,
>>>>>> Giliardy Correia Arena.
>>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>> Em ter, 30 de out de 2018 às 17:57, Giliardy Arena <
>>>>>> giliardy.arena em gmail.com> escreveu:
>>>>>>
>>>>>>> Caros,
>>>>>>> Boa tarde.
>>>>>>>
>>>>>>> Estou aprendendo e estudando sobre o Asterisk.
>>>>>>> Atualmente administro um Cisco Call Manager e a minha ideia é usar o
>>>>>>> Asterisk para gravar ligações recebidas do Call Manager.
>>>>>>>
>>>>>>> Fiz a integração do Asterisk com o Call Manager com sucesso.
>>>>>>>
>>>>>>> Estou com problema para entender o motivo do Asterisk demorar para
>>>>>>> conectar a ligação a uma extensão. Tenho pesquisado, mas com dificuldades
>>>>>>> para entender como debugar.
>>>>>>>
>>>>>>> Criei a seguinte extensão, que atende sozinha e grava.
>>>>>>>
>>>>>>> exten => 2005,1,Answer()
>>>>>>> exten =>
>>>>>>> 2005,n,MixMonitor(Ramal-${CALLERID(num)}-Em-${STRFTIME(${EPOCH},,%d-%m-%Y-%H-%M)}.wav)
>>>>>>> exten => 2005,n,WaitForSilence(10000|6)
>>>>>>> exten => 2005,n,Hangup
>>>>>>>
>>>>>>>
>>>>>>> Também experimentei o mesmo sintoma através de uma extensão que
>>>>>>> criei e loguei numa softphone.
>>>>>>>
>>>>>>> - Ativei Debug full , mas não tem nenhuma mensagem importante.
>>>>>>> Apenas o que vejo na CLI do asterisk
>>>>>>>
>>>>>>> - Na CLI do Asterisk só vejo log quando a chamada efetivamente é
>>>>>>> conectada, não sei se consigo ver desde o momento que ele recebe a
>>>>>>> requisição.
>>>>>>>
>>>>>>> - Fiz um TCPDUMP e realmente me parece que é o Asterisk demorando a
>>>>>>> conectar a extensão, mas via TCPDUMP não tenho detalhes para entender e
>>>>>>> ajustar. Demora aproximadamente 30segundos após chamar do Call Manager.
>>>>>>>
>>>>>>>
>>>>>>> Alguém pode me dar um help de por onde eu posso rastrear para tentar
>>>>>>> corrigir ?
>>>>>>>
>>>>>>> Obrigado!
>>>>>>>
>>>>>>> Atenciosamente,
>>>>>>> Giliardy Correia Arena.
>>>>>>>
>>>>>>>
>>>>>>> _______________________________________________
> KHOMP: completa linha de placas externas FXO, FXS, GSM e E1
> Media Gateways de 1 a 64 E1s para SIP com R2, ISDN e SS7
> Intercomunicador e acesso remoto via rede IP e telefones IP
> Conheça todo o portfólio em www.Khomp.com
> _______________________________________________
> Para remover seu email desta lista, basta enviar um email em branco para
> asteriskbrasil-unsubscribe em listas.asteriskbrasil.org
-------------- Próxima Parte ----------
Um anexo em HTML foi limpo...
URL: <http://asteriskbrasil.org/pipermail/asteriskbrasil/attachments/20181102/21fedd97/attachment-0001.html>
Mais detalhes sobre a lista de discussão AsteriskBrasil