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 <[email protected]> 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 < > [email protected]> 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:[email protected]:5060 SIP/2.0 >> 19:23:11.496042 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >> INVITE sip:[email protected]:5060 SIP/2.0 >> 19:23:12.507249 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >> INVITE sip:[email protected]:5060 SIP/2.0 >> 19:23:14.513145 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >> INVITE sip:[email protected]: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:[email protected]: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:[email protected]: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:[email protected]: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:[email protected]: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 < >> [email protected]> 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:[email protected]:5060 SIP/2.0 >>> 16:47:32.254307 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >>> INVITE sip:[email protected]:5060 SIP/2.0 >>> 16:47:33.258050 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >>> INVITE sip:[email protected]:5060 SIP/2.0 >>> 16:47:35.272582 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >>> INVITE sip:[email protected]: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:[email protected]: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:[email protected]: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:[email protected]:5060 SIP/2.0 >>> 16:47:59.755067 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >>> ACK sip:[email protected]:5060 SIP/2.0 >>> 16:47:59.756284 IP cucmservice01.sip > asterisk.ogmaster.local.sip: SIP: >>> ACK sip:[email protected]: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: >>> [email protected] (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 >>> [email protected] - 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 ' >>> [email protected]:5060' >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Initializing initreq for >>> method OPTIONS - callid >>> [email protected]: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 ' >>> [email protected]:5060' >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Initializing initreq for >>> method OPTIONS - callid >>> [email protected]: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 ' >>> [email protected]:5060' >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Initializing initreq for >>> method OPTIONS - callid >>> [email protected]: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: >>> [email protected]:5060 (Checking To) >>> --From tag as2ee346e2 --To-tag 348178859 >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Stopping retransmission on ' >>> [email protected]:5060' of Request 102: >>> Match Found >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: = Looking for Call ID: >>> [email protected]:5060 (Checking To) >>> --From tag as138ca155 --To-tag 802041871 >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Stopping retransmission on ' >>> [email protected]:5060' of Request 102: >>> Match Found >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Destroying SIP dialog >>> [email protected]:5060 >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Destroying SIP dialog >>> [email protected]:5060 >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: = Looking for Call ID: >>> [email protected]:5060 (Checking To) >>> --From tag as34b82738 --To-tag 605276003 >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Stopping retransmission on ' >>> [email protected]:5060' of Request 102: >>> Match Found >>> [Oct 31 15:35:21] DEBUG[31072] chan_sip.c: Destroying SIP dialog >>> [email protected]:5060 >>> [Oct 31 15:35:36] DEBUG[31072] chan_sip.c: Auto destroying SIP dialog ' >>> [email protected]' >>> [Oct 31 15:35:36] DEBUG[31072] chan_sip.c: Destroying SIP dialog >>> [email protected] >>> [Oct 31 15:35:43] DEBUG[31072] chan_sip.c: Auto destroying SIP dialog ' >>> [email protected]' >>> [Oct 31 15:35:43] DEBUG[31072] chan_sip.c: Destroying SIP dialog >>> [email protected] >>> [Oct 31 15:35:52] DEBUG[31072] chan_sip.c: Auto destroying SIP dialog ' >>> [email protected]' >>> [Oct 31 15:35:52] DEBUG[31072] chan_sip.c: Destroying SIP dialog >>> [email protected] >>> [Oct 31 15:36:04] DEBUG[31072] chan_sip.c: = Looking for Call ID: >>> [email protected] (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 >>> [email protected] - 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: >>> [email protected] (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 >>> [email protected] - 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 < >>> [email protected]> escreveu: >>> >>>> Olá pessoal ! >>>> Alguma ajuda ? Alguma dica ? >>>> >>>> Obrigado >>>> >>>> >>>> Atenciosamente, >>>> Giliardy Correia Arena. >>>> >>>> >>>> >>>> >>>> Em qua, 31 de out de 2018 às 10:58, Giliardy Arena < >>>> [email protected]> 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 < >>>>> [email protected]> 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 < >>>>>> [email protected]> 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 > [email protected]
_______________________________________________ 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 [email protected]
