Anyone know why it took 3 hours to process the AMD?

All installation and configuration problems and questions

Moderators: gerski, enjay, williamconley, Op3r, Staydog, gardo, mflorell, MJCoate, mcargile, Kumba, Michael_N

Anyone know why it took 3 hours to process the AMD?

Postby jessiekidfernando » Tue Oct 01, 2019 4:24 pm

Anyone know why it took 3 hours to process the AMD?

Verbose log:

Code: Select all
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] pbx.c: Executing [817194159096@default:1] AGI("Local/817194159096@default-00005ac4;2", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] res_agi.c: AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OUTSOURC))
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] res_agi.c: <Local/817194159096@default-00005ac4;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] pbx.c: Executing [817194159096@default:2] Set("Local/817194159096@default-00005ac4;2", "CallerIDString=7192805887") in new stack
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] pbx.c: Executing [817194159096@default:3] Set("Local/817194159096@default-00005ac4;2", "CALLERID(num)=+17192805887") in new stack
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] pbx.c: Executing [817194159096@default:4] NoOp("Local/817194159096@default-00005ac4;2", "CallerID : +17192805887") in new stack
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] pbx.c: Executing [817194159096@default:5] Dial("Local/817194159096@default-00005ac4;2", "SIP/magna1/17194159096,,tTo") in new stack
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] netsock2.c: Using SIP RTP CoS mark 5
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] chan_sip.c: Audio is at 14746
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] chan_sip.c: Adding codec ulaw to SDP
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] chan_sip.c: Reliably Transmitting (NAT) to 168.215.247.130:5060:
[Sep 26 12:26:57] VERBOSE[29974][C-0000a5d9] app_dial.c: Called SIP/magna1/17194159096
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] sip/route.c: sip_route_dump: route/path hop: <sip:168.215.247.130:5060;lr>
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] sip/route.c: sip_route_dump: route/path hop: <sip:sansay1773336743rdb162415@67.221.11.67:5060;lr;transport=udp>
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Found RTP audio format 0
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Found RTP audio format 101
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Found audio description format PCMU for ID 0
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Found audio description format telephone-event for ID 101
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Capabilities: us - (ulaw), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw)
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] res_rtp_asterisk.c: 0x7f1e880e5e40 -- Strict RTP learning after remote address set to: 192.40.216.125:12312
[Sep 26 12:27:15] VERBOSE[4072][C-0000a5d9] chan_sip.c: Peer audio RTP is at port 192.40.216.125:12312
[Sep 26 12:27:15] VERBOSE[29974][C-0000a5d9] app_dial.c: SIP/magna1-00004da0 is making progress passing it to Local/817194159096@default-00005ac4;2
[Sep 26 12:27:19] VERBOSE[4072][C-0000a5d9] sip/route.c: sip_route_dump: route/path hop: <sip:168.215.247.130:5060;lr>
[Sep 26 12:27:19] VERBOSE[4072][C-0000a5d9] sip/route.c: sip_route_dump: route/path hop: <sip:sansay1773336743rdb162415@67.221.11.67:5060;lr;transport=udp>
[Sep 26 12:27:19] VERBOSE[4072][C-0000a5d9] chan_sip.c: Transmitting (NAT) to 168.215.247.130:5060:
[Sep 26 12:27:19] VERBOSE[29974][C-0000a5d9] app_dial.c: SIP/magna1-00004da0 answered Local/817194159096@default-00005ac4;2
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] bridge_channel.c: Channel SIP/magna1-00004da0 joined 'simple_bridge' basic-bridge <f0a91fb9-839f-41b7-b611-634166647178>
[Sep 26 12:27:19] VERBOSE[29974][C-0000a5d9] bridge_channel.c: Channel Local/817194159096@default-00005ac4;2 joined 'simple_bridge' basic-bridge <f0a91fb9-839f-41b7-b611-634166647178>
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] bridge_channel.c: Channel SIP/magna1-00004da0 left 'simple_bridge' basic-bridge <f0a91fb9-839f-41b7-b611-634166647178>
[Sep 26 12:27:19] VERBOSE[29974][C-0000a5d9] bridge_channel.c: Channel Local/817194159096@default-00005ac4;2 left 'simple_bridge' basic-bridge <f0a91fb9-839f-41b7-b611-634166647178>
[Sep 26 12:27:19] VERBOSE[29974][C-0000a5d9] pbx.c: Spawn extension (default, 817194159096, 5) exited non-zero on 'Local/817194159096@default-00005ac4;2'
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] pbx.c: Executing [8387@default:1] Playback("SIP/magna1-00004da0", "sip-silence") in new stack
[Sep 26 12:27:19] VERBOSE[29974][C-0000a5d9] pbx.c: Executing [h@default:1] AGI("Local/817194159096@default-00005ac4;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----22-----0-----SIP 200 OK)") in new stack
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] file.c: <SIP/magna1-00004da0> Playing 'sip-silence.gsm' (language 'en')
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] pbx.c: Executing [8387@default:2] AGI("SIP/magna1-00004da0", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] res_agi.c: AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OUTSOURC))
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] res_agi.c: <SIP/magna1-00004da0>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] pbx.c: Executing [8387@default:3] AMD("SIP/magna1-00004da0", "2000,2000,1000,5000,120,50,4,256") in new stack
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] app_amd.c: AMD: SIP/magna1-00004da0 (N/A) (N/A) (Fmt: slin)
[Sep 26 12:27:19] VERBOSE[31234][C-0000a5d9] app_amd.c: AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256] maximumWordLength [5000]
[Sep 26 12:27:20] VERBOSE[29974][C-0000a5d9] res_agi.c: <Local/817194159096@default-00005ac4;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----22-
[Sep 26 15:27:21] VERBOSE[4072][C-0000a5d9] chan_sip.c: Sending to 168.215.247.130:5060 (NAT)
[Sep 26 15:27:21] VERBOSE[4072][C-0000a5d9] chan_sip.c: Scheduling destruction of SIP dialog '683ef8e478cb5ad75cbc76e068767b3a@3.1.193.59:5060' in 13824 ms (Method: BYE)
[Sep 26 15:27:21] VERBOSE[4072][C-0000a5d9] chan_sip.c:
[Sep 26 15:27:21] VERBOSE[31234][C-0000a5d9] app_amd.c: AMD: Channel [SIP/magna1-00004da0]. HANGUP
[Sep 26 15:27:21] VERBOSE[31234][C-0000a5d9] pbx.c: Executing [h@default:1] AGI("SIP/magna1-00004da0", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Sep 26 15:27:21] VERBOSE[31234][C-0000a5d9] res_agi.c: <SIP/magna1-00004da0>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, returning 0




Dialplan:

Code: Select all
'8387' =>           1. Playback(sip-silence)                      [pbx_config]
                    2. AGI(agi://127.0.0.1:4577/call_log)         [pbx_config]
                    3. AMD(2000,2000,1000,5000,120,50,4,256)      [pbx_config]
                    4. AGI(VD_amd.agi,${EXTEN})                   [pbx_config]
                    5. AGI(agi-VDAD_ALL_outbound.agi,NORMAL-----LO) [pbx_config]
                    6. Hangup()                                   [pbx_config]
OS: Linux version 4.4.155-68-default (geeko@buildhost) (gcc version 4.8.5 (SUSE Linux) )
VERSION: 2.14-717a
BUILD: 190724-1603
Asterisk: 13.21.1-vici
Dahdi: 2.11.1
jessiekidfernando
 
Posts: 152
Joined: Fri Feb 08, 2019 5:49 pm

Re: Anyone know why it took 3 hours to process the AMD?

Postby ambiorixg12 » Tue Oct 01, 2019 5:00 pm

Code: Select all
[Sep 26 15:27:21] VERBOSE[4072][C-0000a5d9] chan_sip.c: Scheduling destruction of SIP dialog '683ef8e478cb5ad75cbc76e068767b3a@3.1.193.59:5060' in 13824 ms (Method: BYE


More than AMD script issue, think there is some NAT issue and a lack of response,
ambiorixg12
 
Posts: 448
Joined: Tue Sep 17, 2013 10:35 pm

Re: Anyone know why it took 3 hours to process the AMD?

Postby jessiekidfernando » Thu Oct 17, 2019 10:38 am

Hello Everyone,

Can we upgrade the asterisk instead?

https://issues.asterisk.org/jira/browse/ASTERISK-28143

May I know what are the possible implications if we will upgrade it.. Or May I know what are the things that I need to consider when upgrading the asterisk version as stated on the above link?
OS: Linux version 4.4.155-68-default (geeko@buildhost) (gcc version 4.8.5 (SUSE Linux) )
VERSION: 2.14-717a
BUILD: 190724-1603
Asterisk: 13.21.1-vici
Dahdi: 2.11.1
jessiekidfernando
 
Posts: 152
Joined: Fri Feb 08, 2019 5:49 pm

Re: Anyone know why it took 3 hours to process the AMD?

Postby mflorell » Thu Oct 17, 2019 2:36 pm

Yes, you should be able to upgrade to Asterisk 13.27.0. That is the latest version we are using on VICIhost right now.
mflorell
Site Admin
 
Posts: 18335
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Re: Anyone know why it took 3 hours to process the AMD?

Postby jessiekidfernando » Tue Oct 22, 2019 9:16 am

Hi Mflorell,

Thanks for your response. By the way, is there a recommended process or documentation about upgrading it to 13.27? I have upgraded one of our server from a source and just by initiating

*./configure
*make menuselect
*make install

Then copying the entire conf files from the existing /etc/asterisk/

Thanks in advance.

Regards,

Jessie Kid Fernando
OS: Linux version 4.4.155-68-default (geeko@buildhost) (gcc version 4.8.5 (SUSE Linux) )
VERSION: 2.14-717a
BUILD: 190724-1603
Asterisk: 13.21.1-vici
Dahdi: 2.11.1
jessiekidfernando
 
Posts: 152
Joined: Fri Feb 08, 2019 5:49 pm

Re: Anyone know why it took 3 hours to process the AMD?

Postby jessiekidfernando » Wed Oct 23, 2019 12:13 pm

Also I am just curious if this is cause by the WaitForSilence when being envoked by /var/lib/asterisk/agi-bin/VD_amd.agi. See logs below. As you can see this is not an active call nor routed to an agent but the channel was still active.

Code: Select all
[root@dialer-pq-apse1-lp27 centos]# asterisk -rx 'sip show channels' | grep 22f53d3c4cb
168.215.247.130  15805146933      22f53d3c4cb7d4d  (ulaw)           No       Tx: ACK                    magna1



[root@dialer-pq-apse1-lp27 centos]# cat /var/log/asterisk/messages | grep C-00001efb
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] pbx.c: Executing [815805146933@default:1] AGI("Local/815805146933@default-00001122;2", "agi://127.0.0.1:4577/call_log") in new stack
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] res_agi.c: AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OUTSOURC))
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] res_agi.c: <Local/815805146933@default-00001122;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] pbx.c: Executing [815805146933@default:2] Set("Local/815805146933@default-00001122;2", "CallerIDString=5803240756") in new stack
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] pbx.c: Executing [815805146933@default:3] Set("Local/815805146933@default-00001122;2", "CALLERID(num)=+15803240756") in new stack
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] pbx.c: Executing [815805146933@default:4] NoOp("Local/815805146933@default-00001122;2", "CallerID : +15803240756") in new stack
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] pbx.c: Executing [815805146933@default:5] Dial("Local/815805146933@default-00001122;2", "SIP/magna1/15805146933,,tTo") in new stack
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] netsock2.c: Using SIP RTP CoS mark 5
[Oct 23 11:32:56] VERBOSE[12531][C-00001efb] app_dial.c: Called SIP/magna1/15805146933
[Oct 23 11:33:01] VERBOSE[5183][C-00001efb] res_rtp_asterisk.c: 0x7fd5180aa070 -- Strict RTP learning after remote address set to: 69.252.36.40:10810
[Oct 23 11:33:01] VERBOSE[12531][C-00001efb] app_dial.c: SIP/magna1-00000f33 is making progress passing it to Local/815805146933@default-00001122;2
[Oct 23 11:33:10] VERBOSE[12531][C-00001efb] app_dial.c: SIP/magna1-00000f33 is making progress passing it to Local/815805146933@default-00001122;2
[Oct 23 11:33:11] VERBOSE[12531][C-00001efb] app_dial.c: SIP/magna1-00000f33 answered Local/815805146933@default-00001122;2
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] bridge_channel.c: Channel SIP/magna1-00000f33 joined 'simple_bridge' basic-bridge <9d0b1ee4-600b-4c57-8952-c8eb06450b88>
[Oct 23 11:33:11] VERBOSE[12531][C-00001efb] bridge_channel.c: Channel Local/815805146933@default-00001122;2 joined 'simple_bridge' basic-bridge <9d0b1ee4-600b-4c57-8952-c8eb06450b88>
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] bridge_channel.c: Channel SIP/magna1-00000f33 left 'simple_bridge' basic-bridge <9d0b1ee4-600b-4c57-8952-c8eb06450b88>
[Oct 23 11:33:11] VERBOSE[12531][C-00001efb] bridge_channel.c: Channel Local/815805146933@default-00001122;2 left 'simple_bridge' basic-bridge <9d0b1ee4-600b-4c57-8952-c8eb06450b88>
[Oct 23 11:33:11] VERBOSE[12531][C-00001efb] pbx.c: Spawn extension (default, 815805146933, 5) exited non-zero on 'Local/815805146933@default-00001122;2'
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] pbx.c: Executing [8387@default:1] Playback("SIP/magna1-00000f33", "sip-silence") in new stack
[Oct 23 11:33:11] VERBOSE[12531][C-00001efb] pbx.c: Executing [h@default:1] AGI("Local/815805146933@default-00001122;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----14-----0-----SIP 200 OK)") in new stack
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] file.c: <SIP/magna1-00000f33> Playing 'sip-silence.gsm' (language 'en')
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] res_rtp_asterisk.c: 0x7fd5180aa070 -- Strict RTP switching to RTP target address 69.252.36.40:10810 as source
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] pbx.c: Executing [8387@default:2] AGI("SIP/magna1-00000f33", "agi://127.0.0.1:4577/call_log") in new stack
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] res_rtp_asterisk.c: 0x7fd5180aa070 -- Strict RTP learning complete - Locking on source address 69.252.36.40:10810
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] res_agi.c: AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OUTSOURC))
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] res_agi.c: <SIP/magna1-00000f33>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] pbx.c: Executing [8387@default:3] AMD("SIP/magna1-00000f33", "2000,2000,1000,5000,120,50,4,256") in new stack
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] app_amd.c: AMD: SIP/magna1-00000f33 (N/A) (N/A) (Fmt: slin)
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] app_amd.c: AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256] maximumWordLength [5000]
[Oct 23 11:33:11] VERBOSE[12896][C-00001efb] app_amd.c: AMD: Channel [SIP/magna1-00000f33]. Changed state to STATE_IN_SILENCE
[Oct 23 11:33:12] VERBOSE[12531][C-00001efb] res_agi.c: <Local/815805146933@default-00001122;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----14-----0-----SIP 200 OK) completed, returning 0
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] app_amd.c: AMD: Channel [SIP/magna1-00000f33]. ANSWERING MACHINE: silenceDuration:2000 initialSilence:2000
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] pbx.c: Executing [8387@default:4] NoOp("SIP/magna1-00000f33", "MACHINE") in new stack
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] pbx.c: Executing [8387@default:5] NoOp("SIP/magna1-00000f33", "INITIALSILENCE-2000-2000") in new stack
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] pbx.c: Executing [8387@default:6] AGI("SIP/magna1-00000f33", "VD_amd.agi,8387") in new stack
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] res_agi.c: <SIP/magna1-00000f33> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct 23 11:33:13] VERBOSE[12896][C-00001efb] res_agi.c: <SIP/magna1-00000f33> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Oct 23 11:33:16] VERBOSE[12896][C-00001efb] res_agi.c: AGI Script Executing Application: (WaitForSilence) Options: (2000,2)
[Oct 23 11:33:16] VERBOSE[12896][C-00001efb] app_waitforsilence.c: Waiting 2 time(s) for 2000ms of silence with 0s timeout



Every 1.0s: asterisk -rx 'sip show channelstats' | grep 168.215.247.130 | sort                                                                                     
168.215.247.130  22f53d3c4cb  01:36:53 0000000580  0000000000 ( 0.00%) 0.0000 0000000003  0000000000 ( 0.00%) 0.0010
OS: Linux version 4.4.155-68-default (geeko@buildhost) (gcc version 4.8.5 (SUSE Linux) )
VERSION: 2.14-717a
BUILD: 190724-1603
Asterisk: 13.21.1-vici
Dahdi: 2.11.1
jessiekidfernando
 
Posts: 152
Joined: Fri Feb 08, 2019 5:49 pm

Re: Anyone know why it took 3 hours to process the AMD?

Postby jessiekidfernando » Thu Oct 24, 2019 12:21 pm

Same goes with this.. As you can see it remains as an active channel causing additional cost on our provider.

Oct 24 11:24:25] VERBOSE[16627][C-00001f43] pbx.c: Executing [818508797407@default:1] AGI("Local/818508797407@default-0000111a;2", "agi://127.0.0.1:4577/call_log") in n
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] res_agi.c: AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OUTSOURC))
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] res_agi.c: <Local/818508797407@default-0000111a;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] pbx.c: Executing [818508797407@default:2] Set("Local/818508797407@default-0000111a;2", "CallerIDString=8508989521") in new s
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] pbx.c: Executing [818508797407@default:3] Set("Local/818508797407@default-0000111a;2", "CALLERID(num)=+18508989521") in new
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] pbx.c: Executing [818508797407@default:4] NoOp("Local/818508797407@default-0000111a;2", "CallerID : +18508989521") in new st
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] pbx.c: Executing [818508797407@default:5] Dial("Local/818508797407@default-0000111a;2", "SIP/magna1/18508797407,,tTo") in ne
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] netsock2.c: Using SIP RTP CoS mark 5
[Oct 24 11:24:25] VERBOSE[16627][C-00001f43] app_dial.c: Called SIP/magna1/18508797407
[Oct 24 11:24:29] VERBOSE[5172][C-00001f43] res_rtp_asterisk.c: 0x7f167807b2c0 -- Strict RTP learning after remote address set to: 173.245.44.26:44640
[Oct 24 11:24:29] VERBOSE[16627][C-00001f43] app_dial.c: SIP/magna1-00000ee8 is making progress passing it to Local/818508797407@default-0000111a;2
[Oct 24 11:24:29] VERBOSE[16627][C-00001f43] app_dial.c: SIP/magna1-00000ee8 redirecting info has changed, passing it to Local/818508797407@default-0000111a;2
[Oct 24 11:24:31] VERBOSE[16627][C-00001f43] app_dial.c: SIP/magna1-00000ee8 is ringing
[Oct 24 11:24:31] VERBOSE[16627][C-00001f43] app_dial.c: SIP/magna1-00000ee8 is making progress passing it to Local/818508797407@default-0000111a;2
[Oct 24 11:24:33] VERBOSE[16627][C-00001f43] app_dial.c: SIP/magna1-00000ee8 answered Local/818508797407@default-0000111a;2
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] bridge_channel.c: Channel SIP/magna1-00000ee8 joined 'simple_bridge' basic-bridge <211cac66-2869-42b4-a976-6e8230aca605>
[Oct 24 11:24:33] VERBOSE[16627][C-00001f43] bridge_channel.c: Channel Local/818508797407@default-0000111a;2 joined 'simple_bridge' basic-bridge <211cac66-2869-42b4-a976>
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] bridge_channel.c: Channel SIP/magna1-00000ee8 left 'simple_bridge' basic-bridge <211cac66-2869-42b4-a976-6e8230aca605>
[Oct 24 11:24:33] VERBOSE[16627][C-00001f43] bridge_channel.c: Channel Local/818508797407@default-0000111a;2 left 'simple_bridge' basic-bridge <211cac66-2869-42b4-a976-6
[Oct 24 11:24:33] VERBOSE[16627][C-00001f43] pbx.c: Spawn extension (default, 818508797407, 5) exited non-zero on 'Local/818508797407@default-0000111a;2'
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] pbx.c: Executing [8387@default:1] Playback("SIP/magna1-00000ee8", "sip-silence") in new stack
[Oct 24 11:24:33] VERBOSE[16627][C-00001f43] pbx.c: Executing [h@default:1] AGI("Local/818508797407@default-0000111a;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI----16-----ANSWER-----8-----0-----SIP 200 OK)") in new stack
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] file.c: <SIP/magna1-00000ee8> Playing 'sip-silence.gsm' (language 'en')
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] res_rtp_asterisk.c: 0x7f167807b2c0 -- Strict RTP switching to RTP target address 173.245.44.26:44640 as source
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] pbx.c: Executing [8387@default:2] AGI("SIP/magna1-00000ee8", "agi://127.0.0.1:4577/call_log") in new stack
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] res_agi.c: AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=OUTSOURC))
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] res_agi.c: <SIP/magna1-00000ee8>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] pbx.c: Executing [8387@default:3] AMD("SIP/magna1-00000ee8", "2000,2000,1000,5000,120,50,4,256") in new stack
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] app_amd.c: AMD: SIP/magna1-00000ee8 (N/A) (N/A) (Fmt: slin)
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] app_amd.c: AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLengthnWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256] maximumWordLength [5000]
[Oct 24 11:24:33] VERBOSE[16786][C-00001f43] app_amd.c: AMD: Channel [SIP/magna1-00000ee8]. Detected Talk, previous silence duration: 40
[Oct 24 11:24:34] VERBOSE[16627][C-00001f43] res_agi.c: <Local/818508797407@default-0000111a;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... -0-----SIP 200 OK) completed, returning 0



168.215.247.130 6ef6c801516 01:56:04 0000000010 0000000000 ( 0.00%) 0.0000 0000000001 0000000000 ( 0.00%) 0.0003
OS: Linux version 4.4.155-68-default (geeko@buildhost) (gcc version 4.8.5 (SUSE Linux) )
VERSION: 2.14-717a
BUILD: 190724-1603
Asterisk: 13.21.1-vici
Dahdi: 2.11.1
jessiekidfernando
 
Posts: 152
Joined: Fri Feb 08, 2019 5:49 pm


Return to Support

Who is online

Users browsing this forum: No registered users and 69 guests