Hi all,
To discard issues with the external SIP trunk service, I've configured a SIP trunk with identical settings of production server in an Asterisk 1.8 VM inside LAN. And I've connected it to VICIdial and I made calls, observing that all calls transferred work correctly. However, the problems come back when calls are made from external SIP trunk service.
About above tests I've noticed an important difference in AGI debug information between both cases:
Call made via Internet SIP trunk:
- Code: Select all
[color=#0040FF]Apr 17 22:11:58] -- Executing [525584210911@trunkinbound:1] AGI("SIP/335 AdvnFeeds-00000f87", "agi-DID_route.agi") in new stack
[Apr 17 22:11:58] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_request: agi-DID_route.agi
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_channel: SIP/335 AdvnFeeds-00000f87
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_language: en
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_type: SIP
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_uniqueid: 1492485118.23714
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callerid: 1000000000
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_calleridname: +1000000000
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingpres: 0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingani2: 0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callington: 0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingtns: 0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_dnid: 525584210911
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_rdnis: unknown
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_context: trunkinbound
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_extension: 525584210911
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_priority: 1
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_enhanced: 0.0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_accountcode:
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_threadid: 140219553224448
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >>
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Rx << SET CONTEXT 31_9
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Rx << SET EXTENSION s
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Rx << SET PRIORITY 1
[Apr 17 22:11:58] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0
[Apr 17 22:11:58] -- <SIP/335 AdvnFeeds-00000f87>AGI Script agi-DID_route.agi completed, returning 0
[Apr 17 22:11:58] -- Executing [s@31_9:1] Answer("SIP/335 AdvnFeeds-00000f87", "") in new stack
[Apr 17 22:11:59] -- Executing [s@31_9:2] AGI("SIP/335 AdvnFeeds-00000f87", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----YES-----31_9-------------------------NO") in new stack
[Apr 17 22:11:59] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_request: agi-VDAD_inbound_calltime_check.agi
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_channel: SIP/335 AdvnFeeds-00000f87
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_language: en
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_type: SIP
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_uniqueid: 1492485118.23714
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callerid: 1000000000
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_calleridname: +1000000000
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingpres: 0
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingani2: 0
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callington: 0
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingtns: 0
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_dnid: 525584210911
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_rdnis: unknown
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_context: 31_9
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_extension: s
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_priority: 2
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_enhanced: 0.0
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_accountcode:
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_threadid: 140219553224448
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_arg_1: CALLMENU-----YES-----31_9-------------------------NO
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >>
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 22:11:59] -- <SIP/335 AdvnFeeds-00000f87> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0 endpos=160
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 22:11:59] -- <SIP/335 AdvnFeeds-00000f87> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 22:11:59] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0 endpos=160
[Apr 17 22:11:59] -- <SIP/335 AdvnFeeds-00000f87>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Apr 17 22:11:59] -- Executing [s@31_9:3] Set("SIP/335 AdvnFeeds-00000f87", "INVCOUNT=0") in new stack
[Apr 17 22:11:59] -- Executing [s@31_9:4] BackGround("SIP/335 AdvnFeeds-00000f87", "MODELXONLINE_MX_111") in new stack
[Apr 17 22:11:59] -- <SIP/335 AdvnFeeds-00000f87> Playing 'MODELXONLINE_MX_111.slin' (language 'en')
[Apr 17 22:11:59] == Spawn extension (default, 9998811112, 3) exited non-zero on 'SIP/voxbone.com-00000f86'
[Apr 17 22:11:59] -- Executing [h@default:1] AGI("SIP/voxbone.com-00000f86", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Apr 17 22:11:59] AGI Tx >> agi_network: yes
[Apr 17 22:11:59] AGI Tx >> agi_network_script: call_log--HVcauses--PRI-----NODEBUG-----0---------------
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_request: agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_channel: SIP/voxbone.com-00000f86
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_language: en
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_type: SIP
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_uniqueid: 1492485115.23713
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_callerid: anonymous
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_calleridname: anonymous
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_callingpres: 0
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_callingani2: 0
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_callington: 0
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_callingtns: 0
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_dnid: 81345790700
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_rdnis: unknown
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_context: default
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_extension: h
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_priority: 1
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_enhanced: 0.0
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_accountcode:
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> agi_threadid: 140219572524800
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >>
[Apr 17 22:11:59] -- <SIP/voxbone.com-00000f86>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Apr 17 22:11:59] <SIP/voxbone.com-00000f86>AGI Tx >> HANGUP
[Apr 17 22:12:01] == Manager 'sendcron' logged on from 127.0.0.1
[Apr 17 22:12:01] == Manager 'sendcron' logged off from 127.0.0.1
[Apr 17 22:12:06] == Manager 'sendcron' logged on from 127.0.0.1
[Apr 17 22:12:06] == Manager 'sendcron' logged off from 127.0.0.1
[Apr 17 22:12:12] DTMF[59942][C-000031bf]: channel.c:4215 __ast_read: DTMF begin '1' received on SIP/335 AdvnFeeds-00000f87
[Apr 17 22:12:12] DTMF[59942][C-000031bf]: channel.c:4219 __ast_read: DTMF begin ignored '1' on SIP/335 AdvnFeeds-00000f87
[Apr 17 22:12:12] DTMF[59942][C-000031bf]: channel.c:4129 __ast_read: DTMF end '1' received on SIP/335 AdvnFeeds-00000f87, duration 260 ms
[Apr 17 22:12:12] DTMF[59942][C-000031bf]: channel.c:4199 __ast_read: DTMF end passthrough '1' on SIP/335 AdvnFeeds-00000f87
[Apr 17 22:12:12] -- Executing [1@31_9:1] AGI("SIP/335 AdvnFeeds-00000f87", "agi-VDAD_ALL_inbound.agi,CID-----LB-----1011-----31_9--------------------998-----1-----111------------------------------") in new stack
[Apr 17 22:12:12] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_request: agi-VDAD_ALL_inbound.agi
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_channel: SIP/335 AdvnFeeds-00000f87
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_language: en
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_type: SIP
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_uniqueid: 1492485118.23714
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callerid: 1000000000
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_calleridname: +1000000000
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingpres: 0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingani2: 0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callington: 0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_callingtns: 0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_dnid: 525584210911
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_rdnis: unknown
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_extension: 1
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_priority: 1
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_enhanced: 0.0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_accountcode:
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_threadid: 140219553224448
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> agi_arg_1: CID-----LB-----1011-----31_9--------------------998-----1-----111------------------------------
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >>
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Rx << GET VARIABLE state
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Rx << GET VARIABLE postal_code
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 22:12:12] -- <SIP/335 AdvnFeeds-00000f87> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0 endpos=160
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 22:12:12] -- <SIP/335 AdvnFeeds-00000f87> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0 endpos=160
[Apr 17 22:12:12] <SIP/335 AdvnFeeds-00000f87>AGI Rx << STREAM FILE ES-QUE-HOLD_Final_111 ""
[Apr 17 22:12:12] -- <SIP/335 AdvnFeeds-00000f87> Playing 'ES-QUE-HOLD_Final_111.slin' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 22:12:16] NOTICE[2483]: chan_sip.c:28445 handle_request_register: Registration from '<sip:9899@192.168.0.245;transport=UDP>' failed for '192.168.0.94:44027' - Wrong password
[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=0 endpos=55745
[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Rx << SET CALLERID "Y4172212120000318703 <1000000000>"
[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 200 result=1
[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Rx << CHANNEL STATUS SIP/335 AdvnFeeds-00000f87
[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 520-Invalid command syntax. Proper usage follows:
[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> Returns the status of the specified <replaceable>channelname</replaceable>. If no channel name is given then returns the status of the current channel.
[Apr 17 22:12:19] Return values:
[Apr 17 22:12:19] <enum>0</enum> - Channel is down and available.
[Apr 17 22:12:19] <enum>1</enum> - Channel is down, but reserved.
[Apr 17 22:12:19] <enum>2</enum> - Channel is off hook.
[Apr 17 22:12:19] <enum>3</enum> - Digits (or equivalent) have been dialed.
[Apr 17 22:12:19] <enum>4</enum> - Line is ringing.
[Apr 17 22:12:19] <enum>5</enum> - Remote end is ringing.
[Apr 17 22:12:19] <enum>6</enum> - Line is up.
[Apr 17 22:12:19] <enum>7</enum> - Line is busy.[Apr 17 22:12:19] <SIP/335 AdvnFeeds-00000f87>AGI Tx >> 520 End of proper usage.[/color]
Please, notice that never is executed the AGENTDIRECT thread.
Call made via local Asterisk SIP trunk:
- Code: Select all
[Apr 17 21:41:43] -- Executing [254728@trunkinbound:1] AGI("SIP/TKVICI2L2-00000f83", "agi-DID_route.agi") in new stack
[Apr 17 21:41:43] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_request: agi-DID_route.agi
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_channel: SIP/TKVICI2L2-00000f83
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_language: en
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_type: SIP
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_uniqueid: 1492483303.23698
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callerid: 00521
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_calleridname: 8001
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callingpres: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callingani2: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callington: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callingtns: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_dnid: 254728
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_rdnis: unknown
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_context: trunkinbound
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_extension: 254728
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_priority: 1
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_enhanced: 0.0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_accountcode:
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_threadid: 140217729246976
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >>
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << SET CONTEXT default
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << SET EXTENSION 99909*1499***DID
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << SET PRIORITY 1
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:41:43] -- <SIP/TKVICI2L2-00000f83>AGI Script agi-DID_route.agi completed, returning 0
[Apr 17 21:41:43] -- Executing [99909*1499***DID@default:1] Answer("SIP/TKVICI2L2-00000f83", "") in new stack
[Apr 17 21:41:43] -- Executing [99909*1499***DID@default:2] AGI("SIP/TKVICI2L2-00000f83", "agi-VDAD_ALL_inbound.agi") in new stack
[Apr 17 21:41:43] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_request: agi-VDAD_ALL_inbound.agi
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_channel: SIP/TKVICI2L2-00000f83
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_language: en
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_type: SIP
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_uniqueid: 1492483303.23698
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callerid: 00521
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_calleridname: 8001
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callingpres: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callingani2: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callington: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_callingtns: 0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_dnid: 254728
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_rdnis: unknown
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_context: default
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_extension: 99909*1499***DID
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_priority: 2
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_enhanced: 0.0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_accountcode:
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> agi_threadid: 140217729246976
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >>
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << GET VARIABLE state
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << GET VARIABLE postal_code
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:41:43] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:41:43] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:41:43] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE ES-QUE-HOLD_Final_111 ""
[Apr 17 21:41:43] -- <SIP/TKVICI2L2-00000f83> Playing 'ES-QUE-HOLD_Final_111.slin' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:41:46] NOTICE[2483]: chan_sip.c:28445 handle_request_register: Registration from '<sip:9899@192.168.0.245;transport=UDP>' failed for '192.168.0.94:44027' - Wrong password
[Apr 17 21:41:50] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=55745
[Apr 17 21:41:50] <SIP/TKVICI2L2-00000f83>AGI Rx << SET CALLERID "Y4172141430000318702 <00521>"
[Apr 17 21:41:50] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=1
[Apr 17 21:41:50] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:50] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:51] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Rx << SET MUSIC ON 1
[Apr 17 21:41:52] -- Started music on hold, class '1', on SIP/TKVICI2L2-00000f83
[Apr 17 21:41:52] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:41:53] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:53] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:54] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:54] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:41:55] -- Stopped music on hold on SIP/TKVICI2L2-00000f83
[Apr 17 21:41:55] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:41:55] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:41:55] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:41:55] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE ES-QUE-HOLD_Final_111 ""
[Apr 17 21:41:55] -- <SIP/TKVICI2L2-00000f83> Playing 'ES-QUE-HOLD_Final_111.slin' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:01] == Manager 'sendcron' logged on from 127.0.0.1
[Apr 17 21:42:01] == Manager 'sendcron' logged off from 127.0.0.1
[Apr 17 21:42:02] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=55745
[Apr 17 21:42:03] <SIP/TKVICI2L2-00000f83>AGI Rx << CHANNEL STATUS SIP/TKVICI2L2-00000f83
[Apr 17 21:42:03] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=6
[Apr 17 21:42:03] == Manager 'sendcron' logged on from 127.0.0.1
[Apr 17 21:42:03] -- Executing [192*168*000*245*78600126@default:1] Goto("Local/192*168*000*245*78600126@default-00001a30;2", "default,78600126,1") in new stack
[Apr 17 21:42:03] -- Goto (default,78600126,1)
[Apr 17 21:42:03] -- Executing [78600126@default:1] MeetMe("Local/192*168*000*245*78600126@default-00001a30;2", "8600126,Fq") in new stack
[Apr 17 21:42:03] -- Executing [83047777777777@vicidial-auto:1] Answer("Local/192*168*000*245*78600126@default-00001a30;1", "") in new stack
[Apr 17 21:42:03] -- Executing [83047777777777@vicidial-auto:2] Playback("Local/192*168*000*245*78600126@default-00001a30;1", "ding") in new stack
[Apr 17 21:42:03] -- <Local/192*168*000*245*78600126@default-00001a30;1> Playing 'ding.slin' (language 'en')
[Apr 17 21:42:04] -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/192*168*000*245*78600126@default-00001a30;1", "") in new stack
[Apr 17 21:42:04] == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/192*168*000*245*78600126@default-00001a30;1'
[Apr 17 21:42:04] -- Executing [h@vicidial-auto:1] AGI("Local/192*168*000*245*78600126@default-00001a30;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Apr 17 21:42:04] AGI Tx >> agi_network: yes
[Apr 17 21:42:04] AGI Tx >> agi_network_script: call_log--HVcauses--PRI-----NODEBUG-----16---------------
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_request: agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_channel: Local/192*168*000*245*78600126@default-00001a30;1
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_language: en
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_type: Local
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_uniqueid: 1492483323.23699
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_callerid: ding
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_calleridname: ding
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_callingpres: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_callingani2: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_callington: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_callingtns: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_dnid: unknown
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_rdnis: unknown
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_context: vicidial-auto
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_extension: h
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_priority: 1
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_enhanced: 0.0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_accountcode:
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> agi_threadid: 140219574048512
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >>
[Apr 17 21:42:04] -- <Local/192*168*000*245*78600126@default-00001a30;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;1>AGI Tx >> HANGUP
[Apr 17 21:42:04] == Spawn extension (default, 78600126, 1) exited non-zero on 'Local/192*168*000*245*78600126@default-00001a30;2'
[Apr 17 21:42:04] -- Executing [h@default:1] AGI("Local/192*168*000*245*78600126@default-00001a30;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Apr 17 21:42:04] AGI Tx >> agi_network: yes
[Apr 17 21:42:04] AGI Tx >> agi_network_script: call_log--HVcauses--PRI-----NODEBUG-----0---------------
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_request: agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_channel: Local/192*168*000*245*78600126@default-00001a30;2
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_language: en
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_type: Local
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_uniqueid: 1492483323.23700
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_version: 11.22.0-vici
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_callerid: ding
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_calleridname: ding
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_callingpres: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_callingani2: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_callington: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_callingtns: 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_dnid: unknown
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_rdnis: unknown
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_context: default
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_extension: h
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_priority: 1
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_enhanced: 0.0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_accountcode:
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> agi_threadid: 140217782576896
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >>
[Apr 17 21:42:04] -- <Local/192*168*000*245*78600126@default-00001a30;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Apr 17 21:42:04] <Local/192*168*000*245*78600126@default-00001a30;2>AGI Tx >> HANGUP
[Apr 17 21:42:04] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:42:04] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:04] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:42:04] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:42:04] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:04] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:42:04] == Manager 'sendcron' logged off from 127.0.0.1
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:42:05] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:42:05] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:42:05] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << STREAM FILE sip-silence ""
[Apr 17 21:42:05] -- <SIP/TKVICI2L2-00000f83> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0 endpos=160
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << SET CONTEXT default
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << SET EXTENSION 192*168*000*245*8600126
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Rx << SET PRIORITY 1
[Apr 17 21:42:05] <SIP/TKVICI2L2-00000f83>AGI Tx >> 200 result=0
[Apr 17 21:42:05] -- <SIP/TKVICI2L2-00000f83>AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Apr 17 21:42:05] -- Executing [192*168*000*245*8600126@default:1] Goto("SIP/TKVICI2L2-00000f83", "default,8600126,1") in new stack
[Apr 17 21:42:05] -- Goto (default,8600126,1)
[Apr 17 21:42:05] -- Executing [8600126@default:1] MeetMe("SIP/TKVICI2L2-00000f83", "8600126,F") in new stack
[Apr 17 21:42:05] == Manager 'sendcron' logged on from 127.0.0.1
[Apr 17 21:42:05] -- Executing [58600126@default:1] MeetMe("Local/58600126@default-00001a31;2", "8600126,Fmq") in new stack
[Apr 17 21:42:05] -- Executing [8309@default:1] Answer("Local/58600126@default-00001a31;1", "") in new stack
[Apr 17 21:42:05] -- Executing [8309@default:2] Monitor("Local/58600126@default-00001a31;1", "wav,20170417-214204_00521") in new stack
[Apr 17 21:42:05] -- Executing [8309@default:3] Wait("Local/58600126@default-00001a31;1", "10800") in new stack
A relevant information is that VICIdail server is behind a pfSense firewall. However, also it's important to mention that all the calls are passing without issues, except when the call must be transferred.
Thanks in advance for your help.