Hola muchachos, buenos días.
He instalado Issabel en mi empresa, reemplazando una centralita Daewoo que dejó de funcionar (Murió su placa mother). He utilizado la placa E1 que trae esa Daewoo en un servidor nuevo y he instalado La ultima versión de Issabel.
La Placa es una Sangoma Híbrida B601:
https://www.sangoma.com/products/hybrid-telephony-cards/
04:01.0 Network controller: Sangoma Technologies Corp. Device 0025
wanrouter status
Devices currently active:
wanpipe1 wanpipe2
Wanpipe Config:
Device name | Protocol Map | Adapter | IRQ | Slot/IO | If's | CLK | Baud rate |
wanpipe2 | N/A | A200/A400/B600/B700/B800/B610| 19 | 1 | 1 | N/A | 0 |
wanpipe1 | N/A | A101/1D/2/2D/4/4D/8/8D/16/16D| 19 | 1 | 1 | N/A | 0 |
Wanrouter Status:
Device name | Protocol | Station | Status |
wanpipe2 | A-ANALOG | N/A | Connected |
wanpipe1 | AFT TE1 | N/A | Connected |
He instalado los drivers de la misma con el script additional-drivers.sh provisto en los foros.
Mi trama digital es de Telefónica de Argentina. Ya he configurado todo como Dios Manda, pero el problema que estoy teniendo es que en las llamadas salientes se estan cortando en tiempos aleatoreos. Las llamadas entrantes no tengo problemas. Y las llamadas entre internos tambien funcionan correctamente. Solo ocurren en llamadas salientes.
Veo este error en el log.
[2017-08-14 11:27:02] ERROR[6819] chan_dahdi.c: Chan 1 - Protocol error. Reason = Invalid CAS, R2 State = Clear Forward Transmitted, MF state = MF Engine Off, MF Group = Forward Group I, CAS = 0x04
[2017-08-14 11:27:02] ERROR[6819] chan_dahdi.c: MFC/R2 protocol error on chan 1: Invalid CAS
He buscado en google a mas no poder, he probado absolutamente de todo, pero no encuentro solución al problema. Mi pregunta es, a alguien le pasa lo mismo con esta versión? será la version de asterisl? libropenr2 ?
asterisk-11.25.0-0.el7.centos.x86_64
libopenr2-1.3.3-1.el7.centos.x86_64
libpri-1.4.15-0.el7.centos.x86_64
dahdi-2.10.2-0.el7.centos.x86_64
dahdi-linux-2.10.2-1dkms.noarch
Les pregunto esto , porque hasta he puesto las mismas configuraciones de la Daewoo, que no ocurrian estos fallos de llamadas salientes, y aqui si lo hace.
Les paso mis config por las dudas.
cat /etc/asterisk/chan_dahdi.conf
; Auto-generated by /usr/sbin/hardware_detector
[trunkgroups]
[channels]
context=from-pstn
signalling=fxs_ks
rxwink=300 ; Atlas seems to use long (250ms) winks
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=no
echocancelwhenbridged=no
;faxdetect=incoming
;echotraining=800
rxgain=0.0
txgain=0.0
callgroup=1
pickupgroup=1
;relaxdtmf=yes
;Uncomment these lines if you have problems with the disconection of your analog lines
busydetect=yes
;callerid=yes
;descomente para probar 4-7-17 a las 11.54 por problemas de desconeccion, la opcion de busycount y la modifique de 3 a 8.
busycount=3
language=es
;immediate=no
signalling=mfcr2
mfcr2_variant=ar
mfcr2_get_ani_first=no
mfcr2_max_ani=14
mfcr2_max_dnis=14
mfcr2_category=national_subscriber
mfcr2_logdir=mfcr2
mfcr2_logging=cas
;mfcr2_allow_collect_calls=yes
mfcr2_call_files=yes
mfcr2_logging=all
;mfcr2_mfback_timeout=-1
;mfcr2_metering_pulse_timeout=-1
;mfcr2_dtmf_detection=no
;mfcr2_dtmf_dialing=no
;mfcr2_advanced_protocol_file=/etc/openr2/r2proto.conf
context=default
group=0
echocancel=yes
txgain=0.0
rxgain=0.0
channel => 1-15,17-31
;CardId=3&PortNumber=1&ChannelNumber=32
context=default
group=1
echocancel=yes
txgain=0.0
rxgain=0.0
faxdetect=no
signalling=fxs_ks
channel => 32
;CardId=3&PortNumber=2&ChannelNumber=33
context=default
group=1
echocancel=yes
txgain=0.0
rxgain=0.0
faxdetect=no
signalling=fxs_ks
channel => 33
;CardId=3&PortNumber=3&ChannelNumber=34
context=default
group=1
echocancel=yes
txgain=0.0
rxgain=0.0
faxdetect=no
signalling=fxs_ks
channel => 34
;CardId=3&PortNumber=4&ChannelNumber=35
context=default
group=1
echocancel=yes
txgain=0.0
rxgain=0.0
faxdetect=no
signalling=fxs_ks
channel => 35
;CardId=3&PortNumber=5&ChannelNumber=36
context=default
group=1
echocancel=yes
txgain=0.0
rxgain=0.0
faxdetect=no
signalling=fxo_ks
channel => 36
#include dahdi-channels.conf
#include chan_dahdi_additional.conf
[root@issabel ~]# cat /etc/asterisk/dahdi-channels.conf
; Autogenerated by /usr/sbin/dahdi_genconf on Thu Jun 22 16:45:03 2017
; If you edit this file and execute /usr/sbin/dahdi_genconf again,
; your manual changes will be LOST.
; Dahdi Channels Configurations (chan_dahdi.conf)
;
; This is not intended to be a complete chan_dahdi.conf. Rather, it is intended
; to be #include-d by /etc/chan_dahdi.conf that will include the global settings
;
; Span 1: WPT1/0 "wanpipe1 card 0" ESF/B8ZS RED
group=0,11
context=from-pstn
switchtype=national
signalling=mfcr2
channel => 1-15,17-31
context=default
group=63
; Span 2: WRTDM/0 "wrtdm Board 1" (MASTER)
;;; line="25 WRTDM/0/0 FXSKS (EC: WANPIPE_HWEC - INACTIVE)"
signalling=fxs_ks
callerid=asreceived
group=0
context=from-pstn
channel => 32
callerid=
group=
context=default
;;; line="26 WRTDM/0/1 FXSKS (EC: WANPIPE_HWEC - INACTIVE)"
signalling=fxs_ks
callerid=asreceived
group=0
context=from-pstn
channel => 33
callerid=
group=
context=default
;;; line="27 WRTDM/0/2 FXSKS (EC: WANPIPE_HWEC - INACTIVE)"
signalling=fxs_ks
callerid=asreceived
group=0
context=from-pstn
channel => 34
callerid=
group=
context=default
;;; line="28 WRTDM/0/3 FXSKS (EC: WANPIPE_HWEC - INACTIVE)"
signalling=fxs_ks
callerid=asreceived
group=0
context=from-pstn
channel => 35
callerid=
group=
context=default
;;; line="29 WRTDM/0/4 FXOKS (EC: WANPIPE_HWEC - INACTIVE)"
signalling=fxo_ks
callerid="Channel 29" <4029>
mailbox=4029
group=5
context=from-internal
channel => 36
callerid=
mailbox=
group=
context=default
[root@issabel ~]# cat /etc/dahdi/system.conf
loadzone=ar
loadzone=br
loadzone=mx
loadzone=us
defaultzone=ar
#CardId=3&PortNumber=6&ChannelNumber=31
span=1,1,0,cas,hdb3
##echocanceller=,1-15:1101
##echocanceller=,17-31:1101
cas=1-15:1101
cas=17-31:1101
#echocanceller=mg2,1-15:1101
#echocanceller=mg2,17-31:1101
#hardhdlc=16
#
##CardId=3&PortNumber=1&ChannelNumber=32
fxsks=32
##echocanceller=,32
#
##CardId=3&PortNumber=2&ChannelNumber=33
fxsks=33
##echocanceller=,33
#
##CardId=3&PortNumber=3&ChannelNumber=34
fxsks=34
##echocanceller=,34
#
##CardId=3&PortNumber=4&ChannelNumber=35
fxsks=35
##echocanceller=,35
#
##CardId=3&PortNumber=5&ChannelNumber=36
fxoks=36
##echocanceller=,36
[root@issabel ~]# cat /etc/wanpipe/wanpipe1.conf
[devices]
wanpipe1 = WAN_AFT_TE1, Comment
[interfaces]
w1g1 = wanpipe1, , TDM_VOICE, Comment
[wanpipe1]
CARD_TYPE = AFT
S514CPU = A
CommPort = PRI
AUTO_PCISLOT = NO
PCISLOT = 1
PCIBUS = 4
FE_MEDIA = E1
FE_LCODE = HDB3
FE_FRAME = NCRC4
FE_LINE = 2
TE_CLOCK = NORMAL
TE_REF_CLOCK = 0
TE_SIG_MODE = CAS
TE_RX_SLEVEL = 430
LBO = 120OH
FE_TXTRISTATE = NO
MTU = 1500
UDPPORT = 9000
TTL = 255
IGNORE_FRONT_END = NO
TDMV_SPAN = 1
TDMV_DCHAN = 0
TDMV_HW_DTMF = YES
[w1g1]
ACTIVE_CH = ALL
TDMV_HWEC = YES
MTU = 8
Esta es la TRAZA del Llamado desde la central a un numero externo hasta que se corta.
Aug 14 12:47:14 issabel kernel: wanpipe1: E1:1 TX RBS A:0 B:0 C:0 D:1
Aug 14 12:47:14 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:1 C:0 D:1
Aug 14 12:47:32 issabel kernel: wanpipe1: E1:1 RX RBS A:0 B:1 C:0 D:1
Aug 14 12:48:12 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:1 C:0 D:1
Aug 14 12:48:12 issabel kernel: wanpipe1: E1:1 RX RBS A:0 B:1 C:0 D:1
Aug 14 12:48:27 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:1 C:0 D:1
Aug 14 12:48:27 issabel kernel: wanpipe1: E1:1 RX RBS A:0 B:1 C:0 D:1
Aug 14 12:49:10 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:1 C:0 D:1
Aug 14 12:49:10 issabel kernel: wanpipe1: E1:1 RX RBS A:0 B:1 C:0 D:1
Aug 14 12:49:25 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:1 C:0 D:1
Aug 14 12:49:25 issabel kernel: wanpipe1: E1:1 RX RBS A:0 B:1 C:0 D:1
Aug 14 12:49:39 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:1 C:0 D:1
Aug 14 12:49:40 issabel kernel: wanpipe1: E1:1 TX RBS A:1 B:0 C:0 D:1
Aug 14 12:49:40 issabel kernel: wanpipe1: E1:1 RX RBS A:1 B:0 C:0 D:1
Aug 14 12:49:40 issabel kernel: wanpipe1: E1:1 TX RBS A:1 B:0 C:0 D:1
Chan 1 - Metering pulse received�MFC/R2 billing pulse received on channel 1
[2017-08-14 12:49:27] DEBUG[7483][C-00000052]: res_rtp_asterisk.c:3971 ast_rtcp_read: Got RTCP report of 88 bytes
[2017-08-14 12:49:28] DEBUG[7472][C-0000004f]: chan_dahdi.c:9948 my_dahdi_write: Write returned -1 (Resource temporarily unavailable) on channel 1
[2017-08-14 12:49:32] DEBUG[6853]: chan_iax2.c:11246 socket_process_helper: Peer 450: got pong, lastms 113, historicms 113, maxms 2000
[2017-08-14 12:49:32] DEBUG[7483][C-00000052]: res_rtp_asterisk.c:3971 ast_rtcp_read: Got RTCP report of 88 bytes
[2017-08-14 12:49:32] DEBUG[7472][C-0000004f]: chan_dahdi.c:9948 my_dahdi_write: Write returned -1 (Resource temporarily unavailable) on channel 1
[2017-08-14 12:49:32] DEBUG[7472][C-0000004f]: chan_dahdi.c:9948 my_dahdi_write: Write returned -1 (Resource temporarily unavailable) on channel 1
[2017-08-14 12:49:33] DEBUG[7472][C-0000004f]: chan_dahdi.c:9948 my_dahdi_write: Write returned -1 (Resource temporarily unavailable) on channel 1
[2017-08-14 12:49:37] DEBUG[7483][C-00000052]: res_rtp_asterisk.c:3971 ast_rtcp_read: Got RTCP report of 88 bytes
[2017-08-14 12:49:39] DEBUG[7472][C-0000004f]: chan_dahdi.c:9445 dahdi_exception: Exception on 12, channel 1
[2017-08-14 12:49:39] DEBUG[7472][C-0000004f]: chan_dahdi.c:8474 dahdi_handle_event: Got event Bits Changed(13) on channel 1 (index 0)
[2017-08-14 12:49:39] DEBUG[7472][C-0000004f]: chan_dahdi.c:8541 dahdi_handle_event: bits changed in chan 1
[2017-08-14 12:49:39] DEBUG[7472][C-0000004f]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - Bits changed from 0x04 to 0x0C
[2017-08-14 12:49:39] DEBUG[7472][C-0000004f]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - CAS Rx << [CLEAR BACK] 0x0C
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - calling timer 8 (r2_metering_pulse) callback
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - Metering pulse timeout expired in state Clear Back Received
Chan 1 - Far end disconnected. Reason: Normal Clearing
MFC/R2 call disconnected on channel 1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:7652 ast_generic_bridge: Didn't get a frame from channel: DAHDI/1-1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_iax2.c:7696 send_command: Callno 505: Blocked sending control frame 20.
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:8146 ast_channel_bridge: Bridge stops bridging channels IAX2/450-505 and DAHDI/1-1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:2664 ast_softhangup_nolock: Soft-Hanging up channel 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'Macro'
-- Executing [h@macro-dialout-trunk:1] Macro("IAX2/450-505", "hangupcall,") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4837 pbx_substitute_variables_helper_full: Function CDR(recordingfile) result is '(null)'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:1] GotoIf("IAX2/450-505", "1?endmixmoncheck") in new stack
-- Goto (macro-hangupcall,s,20)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:20] NoOp("IAX2/450-505", "End of MIXMON check") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:21] GotoIf("IAX2/450-505", "1?nomeetmemon") in new stack
-- Goto (macro-hangupcall,s,39)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:39] NoOp("IAX2/450-505", "End of MEETME check") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:40] GotoIf("IAX2/450-505", "1?noautomon") in new stack
-- Goto (macro-hangupcall,s,45)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:45] NoOp("IAX2/450-505", "TOUCH_MONITOR_OUTPUT=") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:46] GotoIf("IAX2/450-505", "1?noautomon2") in new stack
-- Goto (macro-hangupcall,s,52)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:52] NoOp("IAX2/450-505", "MONITOR_FILENAME=") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:53] GotoIf("IAX2/450-505", "1?noautomon3") in new stack
-- Goto (macro-hangupcall,s,59)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:59] NoOp("IAX2/450-505", "MIXMONITOR_FILENAME=") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:60] GotoIf("IAX2/450-505", "1?noautomon4") in new stack
-- Goto (macro-hangupcall,s,62)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:62] NoOp("IAX2/450-505", "ONETOUCH_RECFILE=") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4837 pbx_substitute_variables_helper_full: Function CDR(recordingfile) result is '(null)'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'NoOp'
-- Executing [s@macro-hangupcall:63] NoOp("IAX2/450-505", "CDR recordingfile set to: ") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: Noop
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:64] GotoIf("IAX2/450-505", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,67)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:67] GotoIf("IAX2/450-505", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,70)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:4905 pbx_substitute_variables_helper_full: Expression result is '1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'GotoIf'
-- Executing [s@macro-hangupcall:70] GotoIf("IAX2/450-505", "1?theend") in new stack
-- Goto (macro-hangupcall,s,72)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: GotoIf
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'AGI'
-- Executing [s@macro-hangupcall:72] AGI("IAX2/450-505", "hangup.agi") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: res_agi.c:3921 agi_exec_full: Hungup channel detected, running agi in dead mode.
-- Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
-- <IAX2/450-505>AGI Script hangup.agi completed, returning 0
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:455 macro_exec: Executed application: AGI
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:5005 pbx_extension_helper: Launching 'Hangup'
-- Executing [s@macro-hangupcall:73] Hangup("IAX2/450-505", "") in new stack
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:2664 ast_softhangup_nolock: Soft-Hanging up channel 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:449 macro_exec: Spawn extension (macro-hangupcall,s,73) exited non-zero on 'IAX2/450-505' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 73) exited non-zero on 'IAX2/450-505' in macro 'hangupcall'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:6305 ast_pbx_h_exten_run: Spawn extension (macro-dialout-trunk,h,1) exited non-zero on 'IAX2/450-505'
== Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:2843 ast_hangup: Hanging up channel 'DAHDI/1-1'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:6659 dahdi_hangup: dahdi_hangup(DAHDI/1-1)
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:6863 dahdi_hangup: Hangup: channel: 1 index = 0, normal = 12, callwait = -1, thirdcall = -1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:6989 dahdi_hangup: disconnecting MFC/R2 call on chan 1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:6623 dahdi_ast_cause_to_r2_cause: ast cause 16 resulted in openr2 cause 6/Normal Clearing
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - CAS Tx >> [CLEAR FORWARD] 0x08
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - CAS Raw Tx >> 0x09
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:5414 dahdi_disable_ec: Disabled echo cancellation on channel 1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:7309 dahdi_setoption: Set option TDD MODE, value: OFF(0) on DAHDI/1-1
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_dahdi.c:5326 update_conf: Updated conferencing on 1, with 0 conference users
-- Hungup 'DAHDI/1-1'
[2017-08-14 12:49:40] DEBUG[6808]: devicestate.c:477 do_state_change: Changing state for DAHDI/1 - state 0 (Unknown)
[2017-08-14 12:49:40] DEBUG[6808]: devicestate.c:452 devstate_event: device 'DAHDI/1' state '0'
[2017-08-14 12:49:40] DEBUG[6876]: app_queue.c:1924 handle_statechange: Device 'DAHDI/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_dial.c:3120 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: app_macro.c:449 _macro_exec: Spawn extension (macro-dialout-trunk,s,22) exited non-zero on 'IAX2/450-505' in macro 'dialout-trunk'
== Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'IAX2/450-505' in macro 'dialout-trunk'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: pbx.c:6789 ast_pbx_run: Spawn extension (from-internal,155458626,7) exited non-zero on 'IAX2/450-505'
== Spawn extension (from-internal, 155458626, 7) exited non-zero on 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:2664 ast_softhangup_nolock: Soft-Hanging up channel 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: channel.c:2843 ast_hangup: Hanging up channel 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[7472][C-0000004f]: chan_iax2.c:5389 iax2_hangup: We're hanging up IAX2/450-505 now...
-- Hungup 'IAX2/450-505'
[2017-08-14 12:49:40] DEBUG[6808]: chan_iax2.c:14470 iax2_devicestate: Checking device state for device 450
[2017-08-14 12:49:40] DEBUG[6808]: chan_iax2.c:14478 iax2_devicestate: Found peer. What's device state of 450? addr=3130972291, defaddr=0 maxms=2000, lastms=113
[2017-08-14 12:49:40] DEBUG[6808]: devicestate.c:477 do_state_change: Changing state for IAX2/450 - state 0 (Unknown)
[2017-08-14 12:49:40] DEBUG[6808]: devicestate.c:452 devstate_event: device 'IAX2/450' state '0'
[2017-08-14 12:49:40] DEBUG[6876]: app_queue.c:1924 handle_statechange: Device 'IAX2/450' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[2017-08-14 12:49:40] DEBUG[6810]: chan_iax2.c:14470 iax2_devicestate: Checking device state for device 450
[2017-08-14 12:49:40] DEBUG[6810]: chan_iax2.c:14478 iax2_devicestate: Found peer. What's device state of 450? addr=3130972291, defaddr=0 maxms=2000, lastms=113
[2017-08-14 12:49:40] DEBUG[6810]: app_queue.c:2026 extension_state_cb: Extension '450@ext-local' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2017-08-14 12:49:40] DEBUG[6856]: chan_iax2.c:2908 sched_delay_remove: schedule decrement of callno used for 186.158.216.131 in 60 seconds
[2017-08-14 12:49:40] DEBUG[6819]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - Bits changed from 0x0C to 0x08
[2017-08-14 12:49:40] DEBUG[6819]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - CAS Rx << [IDLE] 0x08
[2017-08-14 12:49:40] DEBUG[6819]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - Call ended
[2017-08-14 12:49:40] DEBUG[6819]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - CAS Tx >> [IDLE] 0x08
[2017-08-14 12:49:40] DEBUG[6819]: chan_dahdi.c:4592 dahdi_r2_write_log: Chan 1 - CAS Raw Tx >> 0x09
MFC/R2 call end on channel 1