[AsteriskBrasil] Demora para Completar ligação
Giliardy Arena
giliardy.arena em gmail.com
Sexta Novembro 2 13:14:54 -03 2018
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.
>>>>>>
>>>>>>
>>>>>>
-------------- Próxima Parte ----------
Um anexo em HTML foi limpo...
URL: <http://asteriskbrasil.org/pipermail/asteriskbrasil/attachments/20181102/6fb9fe6e/attachment-0001.html>
Mais detalhes sobre a lista de discussão AsteriskBrasil