[AsteriskBrasil] Elastix + A2B sem hangup remoto

Quarta Junho 13 21:40:58 BRT 2012

Estou com o seguinte problema:

Tenho um servidor com Elastix 2.2.0.25 + a2b 1.9.4 e nele tenho uma conta Vono que quando disco através dela, sem o a2b, tudo funciona tranqüilo. Caso eu faça a chamada sair pelo A2B, a chamada é completada sem stress, mas se desligar do lado remoto o a2b ao invés de desligar, pergunta o número para discar, segurando o canal indefinidamente. Se o desligamento for do lado do servidor, funciona sem problema, mesmo com o a2b.

Habilitei o rtp debug e verifico que quando o outro lado desliga ainda ocorre tráfego RTP, então não é dado hangup no canal, esse tráfego não tem fim.
Mas também dei uma olhada com o Wireshark e verifico que o sinal de BYE é dado pelo lado remoto, chega na Vono, que envia para o servidor, mas não é passado para o telefone, que segura o canal.

O mais estranho é que se deixar o a2b de lado, funciona beleza. Então acredito que o problema tá nele. Mas já verifiquei 10x e não encontro nada de errado.

Qualquer luz seria bem-vinda.

João Marcelo Queiroz

Log:

[Jun 12 16:20:08] VERBOSE[18196] config.c:   == Parsing '/etc/asterisk/logger.conf': [Jun 12 16:20:08] VERBOSE[18196] config.c:   == Found
[Jun 12 16:20:08] VERBOSE[18196] logger.c:  Asterisk Queue Logger restarted
[Jun 12 16:20:09] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:12] VERBOSE[15537] netsock2.c:   == Using SIP RTP TOS bits 184
[Jun 12 16:20:12] VERBOSE[15537] netsock2.c:   == Using SIP RTP CoS mark 5
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [0XXXXXXXX em from-internal:1] Macro("SIP/8390-00000078", "user-callerid,SKIPTTL,") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:1] Set("SIP/8390-00000078", "AMPUSER=8390") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:2] GotoIf("SIP/8390-00000078", "0?report") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:3] ExecIf("SIP/8390-00000078", "1?Set(REALCALLERIDNUM=8390)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:4] Set("SIP/8390-00000078", "AMPUSER=8390") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:5] Set("SIP/8390-00000078", "AMPUSERCIDNAME=Ramal") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:6] GotoIf("SIP/8390-00000078", "0?report") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:7] Set("SIP/8390-00000078", "AMPUSERCID=8390") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:8] Set("SIP/8390-00000078", "CALLERID(all)="Ramal" <8390>") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:9] ExecIf("SIP/8390-00000078", "1?Set(CHANNEL(language)=pt_BR)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:10] GotoIf("SIP/8390-00000078", "1?continue") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-user-callerid,s,19)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:19] Set("SIP/8390-00000078", "CALLERID(number)=8390") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:20] Set("SIP/8390-00000078", "CALLERID(name)=Ramal") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-user-callerid:21] NoOp("SIP/8390-00000078", "Using CallerID "Ramal" <8390>") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [0XXXXXXXX em from-internal:2] NoOp("SIP/8390-00000078", "Calling Out Route: SaidaCel") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [0XXXXXXXX em from-internal:3] Set("SIP/8390-00000078", "MOHCLASS=default") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [0XXXXXXXX em from-internal:4] Set("SIP/8390-00000078", "_NODEST=") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [0XXXXXXXX em from-internal:5] Macro("SIP/8390-00000078", "record-enable,8390,OUT,") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-record-enable:1] GotoIf("SIP/8390-00000078", "1?check") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-record-enable,s,4)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-record-enable:4] ExecIf("SIP/8390-00000078", "0?MacroExit()") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-record-enable:5] GotoIf("SIP/8390-00000078", "0?Group:OUT") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-record-enable,s,15)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-record-enable:15] GotoIf("SIP/8390-00000078", "0?IN") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-record-enable:16] ExecIf("SIP/8390-00000078", "1?MacroExit()") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [0XXXXXXXX em from-internal:6] Macro("SIP/8390-00000078", "dialout-trunk,8,0XXXXXXXX,") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:1] Set("SIP/8390-00000078", "DIAL_TRUNK=8") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:2] GosubIf("SIP/8390-00000078", "0?sub-pincheck,s,1") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:3] GotoIf("SIP/8390-00000078", "0?disabletrunk,1") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:4] Set("SIP/8390-00000078", "DIAL_NUMBER=0XXXXXXXX") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:5] Set("SIP/8390-00000078", "DIAL_TRUNK_OPTIONS=tr") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:6] Set("SIP/8390-00000078", "OUTBOUND_GROUP=OUT_8") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:7] GotoIf("SIP/8390-00000078", "1?nomax") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-dialout-trunk,s,9)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:9] GotoIf("SIP/8390-00000078", "0?skipoutcid") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:10] Set("SIP/8390-00000078", "DIAL_TRUNK_OPTIONS=") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:11] Macro("SIP/8390-00000078", "outbound-callerid,8") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:1] ExecIf("SIP/8390-00000078", "0?Set(CALLERPRES()=)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:2] ExecIf("SIP/8390-00000078", "0?Set(REALCALLERIDNUM=8390)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:3] GotoIf("SIP/8390-00000078", "1?normcid") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:6] Set("SIP/8390-00000078", "USEROUTCID=") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:7] Set("SIP/8390-00000078", "EMERGENCYCID=") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:8] Set("SIP/8390-00000078", "TRUNKOUTCID=") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:9] GotoIf("SIP/8390-00000078", "1?trunkcid") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:12] ExecIf("SIP/8390-00000078", "0?Set(CALLERID(all)=)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:13] ExecIf("SIP/8390-00000078", "0?Set(CALLERID(all)=)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:14] ExecIf("SIP/8390-00000078", "0?Set(CALLERID(all)=)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-outbound-callerid:15] ExecIf("SIP/8390-00000078", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:12] GosubIf("SIP/8390-00000078", "1?sub-flp-8,s,1") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em sub-flp-8:1] ExecIf("SIP/8390-00000078", "1?Return()") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:13] Set("SIP/8390-00000078", "OUTNUM=0XXXXXXXX") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:14] Set("SIP/8390-00000078", "custom=AMP") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:15] ExecIf("SIP/8390-00000078", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:16] Macro("SIP/8390-00000078", "dialout-trunk-predial-hook,") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk-predial-hook:1] MacroExit("SIP/8390-00000078", "") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:17] GotoIf("SIP/8390-00000078", "0?bypass,1") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:18] GotoIf("SIP/8390-00000078", "1?customtrunk") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-dialout-trunk,s,22)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:22] Set("SIP/8390-00000078", "pre_num=AMP:Local/") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:23] Set("SIP/8390-00000078", "the_num=OUTNUM") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:24] Set("SIP/8390-00000078", "post_num=@a2billing/n") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:25] GotoIf("SIP/8390-00000078", "1?outnum:skipoutnum") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Goto (macro-dialout-trunk,s,26)
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:26] Set("SIP/8390-00000078", "the_num=0XXXXXXXX") in new stack
[Jun 12 16:20:12] VERBOSE[18614] pbx.c:     -- Executing [s em macro-dialout-trunk:27] Dial("SIP/8390-00000078", "Local/0XXXXXXXX em a2billing/n,300,") in new stack
[Jun 12 16:20:12] VERBOSE[18614] app_dial.c:     -- Called Local/0XXXXXXXX em a2billing/n
[Jun 12 16:20:12] VERBOSE[18615] pbx.c:     -- Executing [0XXXXXXXX em a2billing:1] NoOp("Local/0XXXXXXXX em a2billing-a4ae;2", "A2Billing Start") in new stack
[Jun 12 16:20:12] VERBOSE[18615] pbx.c:     -- Executing [0XXXXXXXX em a2billing:2] AGI("Local/0XXXXXXXX em a2billing-a4ae;2", "a2billing.php,1") in new stack
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/a2billing.php
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_request: a2billing.php
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_channel: Local/0XXXXXXXX em a2billing-a4ae;2
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_language: en
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_type: Local
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_uniqueid: 1339528812.244
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_version: 1.8.7.0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_callerid: 8390
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_calleridname: Ramal
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_callingpres: 0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_callingani2: 0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_callington: 0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_callingtns: 0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_dnid: unknown
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_rdnis: unknown
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_context: a2billing
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_extension: 0XXXXXXXX
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_priority: 2
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_enhanced: 0.0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_accountcode: 
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_threadid: -1336419440
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> agi_arg_1: 1
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << GET VARIABLE IDCONF
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=0
[Jun 12 16:20:12] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << ANSWER
[Jun 12 16:20:12] VERBOSE[18614] app_dial.c:     -- Local/0XXXXXXXX em a2billing-a4ae;1 answered SIP/8390-00000078
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=0
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << SET VARIABLE CHANNEL(language) "br"
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=1
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << SET CALLERID 8390
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=1
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << CHANNEL STATUS Local/0XXXXXXXX em a2billing-a4ae;2
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=6
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << EXEC DIAL SIP/Vono/XXXXXXXXXXX,30,HRgL(336216000:61000:30000)
[Jun 12 16:20:13] VERBOSE[18615] res_agi.c:     -- AGI Script Executing Application: (DIAL) Options: (SIP/Vono/XXXXXXXXXXX,30,HRgL(336216000:61000:30000))
[Jun 12 16:20:13] VERBOSE[18615] netsock2.c:   == Using SIP RTP TOS bits 184
[Jun 12 16:20:13] VERBOSE[18615] netsock2.c:   == Using SIP RTP CoS mark 5
[Jun 12 16:20:13] VERBOSE[18615] app_dial.c:     -- Called SIP/Vono/XXXXXXXXXXX
[Jun 12 16:20:13] VERBOSE[18615] app_dial.c:     -- Local/0XXXXXXXX em a2billing-a4ae;2 requested special control 20, passing it to SIP/Vono-00000079
[Jun 12 16:20:13] VERBOSE[18615] app_dial.c:     -- Local/0XXXXXXXX em a2billing-a4ae;2 requested special control 20, passing it to SIP/Vono-00000079
[Jun 12 16:20:13] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:17] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:17] VERBOSE[18615] app_dial.c:     -- SIP/Vono-00000079 is making progress passing it to Local/0XXXXXXXX em a2billing-a4ae;2
[Jun 12 16:20:17] VERBOSE[18615] app_dial.c:     -- Local/0XXXXXXXX em a2billing-a4ae;2 requested special control 20, passing it to SIP/Vono-00000079
[Jun 12 16:20:17] VERBOSE[18615] app_dial.c:     -- Local/0XXXXXXXX em a2billing-a4ae;2 requested special control 20, passing it to SIP/Vono-00000079
[Jun 12 16:20:18] VERBOSE[18615] app_dial.c:     -- SIP/Vono-00000079 answered Local/0XXXXXXXX em a2billing-a4ae;2
[Jun 12 16:20:21] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:22] ERROR[15537] netsock2.c: getaddrinfo("Vono", "(null)", ...): Name or service not known
[Jun 12 16:20:22] WARNING[15537] acl.c: Unable to lookup 'Vono'
[Jun 12 16:20:22] WARNING[15537] acl.c: Cannot connect
[Jun 12 16:20:22] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:22] NOTICE[15537] chan_sip.c:    -- Registration for 'XXX.XXX em Vono' timed out, trying again (Attempt #55)
[Jun 12 16:20:22] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:23] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:25] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=0
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << GET VARIABLE ANSWEREDTIME
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=1 (9)
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << GET VARIABLE DIALSTATUS
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=1 (ANSWER)
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << CHANNEL STATUS Local/0XXXXXXXX em a2billing-a4ae;2
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=6
[Jun 12 16:20:27] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << GET DATA prepaid-enter-dest 6000 20
[Jun 12 16:20:27] VERBOSE[18615] file.c:     -- <Local/0XXXXXXXX em a2billing-a4ae;2> Playing 'prepaid-enter-dest.gsm' (language 'br')
[Jun 12 16:20:29] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [h em macro-dialout-trunk:1] Macro("SIP/8390-00000078", "hangupcall,") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:1] GotoIf("SIP/8390-00000078", "1?endmixmoncheck") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,9)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:9] NoOp("SIP/8390-00000078", "End of MIXMON check") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:10] GotoIf("SIP/8390-00000078", "1?nomeetmemon") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,15)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:15] NoOp("SIP/8390-00000078", "MEETME_RECORDINGFILE=") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:16] GotoIf("SIP/8390-00000078", "1?noautomon") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,18)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:18] NoOp("SIP/8390-00000078", "TOUCH_MONITOR_OUTPUT=") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:19] GotoIf("SIP/8390-00000078", "1?noautomon2") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,25)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:25] NoOp("SIP/8390-00000078", "MONITOR_FILENAME=") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:26] GotoIf("SIP/8390-00000078", "1?skiprg") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,29)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:29] GotoIf("SIP/8390-00000078", "1?skipblkvm") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,32)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:32] GotoIf("SIP/8390-00000078", "1?theend") in new stack
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Goto (macro-hangupcall,s,34)
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:     -- Executing [s em macro-hangupcall:34] Hangup("SIP/8390-00000078", "") in new stack
[Jun 12 16:20:30] VERBOSE[18614] app_macro.c:   == Spawn extension (macro-hangupcall, s, 34) exited non-zero on 'SIP/8390-00000078' in macro 'hangupcall'
[Jun 12 16:20:30] VERBOSE[18614] features.c:   == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/8390-00000078'
[Jun 12 16:20:30] VERBOSE[18614] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 27) exited non-zero on 'SIP/8390-00000078' in macro 'dialout-trunk'
[Jun 12 16:20:30] VERBOSE[18614] pbx.c:   == Spawn extension (from-internal, 0XXXXXXXX, 6) exited non-zero on 'SIP/8390-00000078'
[Jun 12 16:20:30] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 200 result=-1
[Jun 12 16:20:30] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << STREAM FILE prepaid-invalid-digits "#" 0
[Jun 12 16:20:30] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 511 Command Not Permitted on a dead channel
[Jun 12 16:20:30] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Rx << CHANNEL STATUS Local/0XXXXXXXX em a2billing-a4ae;2
[Jun 12 16:20:30] VERBOSE[18615] res_agi.c: <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Tx >> 511 Command Not Permitted on a dead channel
[Jun 12 16:20:30] VERBOSE[18615] res_agi.c:     -- <Local/0XXXXXXXX em a2billing-a4ae;2>AGI Script a2billing.php completed, returning 4
[Jun 12 16:20:30] VERBOSE[18615] pbx.c:   == Spawn extension (a2billing, 0XXXXXXXX, 2) exited non-zero on 'Local/0XXXXXXXX em a2billing-a4ae;2'
[Jun 12 16:20:32] VERBOSE[18196] asterisk.c:     -- Remote UNIX connection disconnected
[Jun 12 16:20:33] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:37] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:41] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:42] ERROR[15537] netsock2.c: getaddrinfo("Vono", "(null)", ...): Name or service not known
[Jun 12 16:20:42] WARNING[15537] acl.c: Unable to lookup 'Vono'
[Jun 12 16:20:42] WARNING[15537] acl.c: Cannot connect
[Jun 12 16:20:42] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:42] NOTICE[15537] chan_sip.c:    -- Registration for 'XXX.XXX em Vono' timed out, trying again (Attempt #56)
[Jun 12 16:20:43] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:44] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument
[Jun 12 16:20:46] WARNING[15537] chan_sip.c: sip_xmit of 0xb03809b0 (len 344) to (null) returned -1: Invalid argument

Config A2B

interval_len_cardnumber canreinvite = no
sip_additional_parameters trustrpid = yes | sendrpid = yes | canreinvite = no
enable_signup 1
enable_captcha 1
credit 0
callplan_id_list 1,2
activated 0
simultaccess 0
typepaid 1
creditlimit 0
runservice 0
enableexpire 0
expirationdate

expiredays 0
sip_account 1
iax_account 1
activatedbyuser 0
monitor_formatfile gsm
dialcommand_param_call_2did ,60,rHiL(%timeout%:61000:30000)
currency_choose usd, eur, cad, hkd
advanced_mode 0
delete_fk_card 1
type friend
allow ulaw,alaw,gsm,g729
context a2billing
nat yes
amaflag billing
qualify no
host dynamic
dtmfmode RFC2833
description agi-config
asterisk_version 1_8
answer_call 1
play_audio 1
say_goodbye 0
play_menulanguage 0
force_language br
intro_prompt 
min_credit_2call 0
min_duration_2bill 3
notenoughcredit_cardnumber 1
notenoughcredit_assign_newcardnumber_cid 0
use_dnid 1
no_auth_dnid 2400,23
number_try 3
force_callplan_id 
say_balance_after_auth 0
say_balance_after_call 0
say_rateinitial 0
say_timetocall 0
auto_setcallerid 1
force_callerid 
cid_sanitize CID
cid_enable 1
cid_askpincode_ifnot_callerid 1
failover_lc_prefix 0
cid_auto_assign_card_to_cid 0
callerid_authentication_over_cardnumber 0
sip_iax_friends 0
sip_iax_pstn_direct_call_prefix 555
sip_iax_pstn_direct_call 0
ivr_voucher 0
ivr_voucher_prefix 8
jump_voucher_if_min_credit 0
dialcommand_param ,30,HRgL(%timeout%:61000:30000)
dialcommand_param_sipiax_friend ,60,HiL(3600000:61000:30000)
switchdialcommand 0
failover_recursive_limit 2
maxtime_tocall_negatif_free_route 5400
send_reminder 0
record_call 0
monitor_formatfile gsm
agi_force_currency BRL
currency_association usd:dollars,mxn:pesos,eur:euros,all:credit
local_dialing_addcountryprefix 0
max_call_call_2_did 3600
file_conf_enter_destination prepaid-enter-dest
conf_order_menulang en:fr:es
callback_bill_1stleg_ifcall_notconnected 1
international_prefixes 011,00,09
server_GMT GMT-3:00
NULL agi-conf1
asterisk_version 1_8