How to increase calling speed?

All installation and configuration problems and questions

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

How to increase calling speed?

Postby dinstar » Wed Nov 24, 2021 7:35 am

Hello, I have posted ealier regarding slow outbound dailing speed here.

After a bit of research, I think dialing speed is slow due to hopper script reset time of 60secs. Can you pls help me to reset/lower hopper script reset time? I searched in campaign settings bt didn't find it.

Edit: I sat with IST (+5:30) clock on mobile with seconds, and I figured out the issue. Vicidial is dialing every 60secs, even if calls gets NA, Cancelled, Rejected or anything. I have pinpointed this issue, don't know why it is happening.


Pls assist for the same

Thanks
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby mflorell » Wed Nov 24, 2021 9:23 am

What is your campaign's hopper level set to?
mflorell
Site Admin
 
Posts: 17913
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Re: Changing Hopper script running time

Postby dinstar » Wed Nov 24, 2021 9:33 am

Right now it's at 200, earlier it was at 2000.

Tested in both levels, delay is same.
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby dinstar » Wed Nov 24, 2021 1:15 pm

Hello again!
I looked up crontab entries and thought this "* * * * * /usr/share/astguiclient/AST_VDhopper.pl -q" one is of reloading hopper. Then, i put sleep of incremental 5 seconds infront of it and saved it.

I ran campaign again with 2 remote agents and 2 different mobile number repeating in the list. Timed it after opening asterisk cli and still had the samw issue. It's dialing every 1 minute only.........

Pls assist :cry:
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby mflorell » Wed Nov 24, 2021 11:24 pm

What version of Asterisk is installed on your dialers?

What version of Asterisk is set in the Server records in the VICIdial admin web screen?
mflorell
Site Admin
 
Posts: 17913
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Re: Changing Hopper script running time

Postby dinstar » Wed Nov 24, 2021 11:33 pm

Hey Matt thanks for reply.
I don't know what do you mean as 'dialer'?
I have one server of i5 4th gen 8GB Ram 500GB HDD, in which I have installed the following:
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2

Asterisk -R shows this version in it: Asterisk 13.38.2-vici

In Admin>Servers>Modify> Asterisk verison in text box is exactly similar of the above.

Also, while dialing asterisk CLI shows that calls is being placed on 3rd to 4th second of every minute, regardless of what happend to ealier dialed calls. And for my usage, average call time is very low as 15 sec recording is played only, after that many customers disconnect call from their side, heck some even disconnect in 3-4 seconds. So that channel will remain idle till next minute.

On paper it may seem very minuscule idle time, bt in my application it is performing at its 50% efficiency.
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby dinstar » Thu Nov 25, 2021 1:11 am

Hello Again! I spent some time researching and looking at my system. Here's what I figured out, this info will be helpful in understanding the issue.
Specs are posted above.

My usage: Press 1 campaigns without live agents with 15 second recording for financial product. I am based in India and using dinstar GSM Gateway of 16 ports, currently using 8 ports only. We will call the press 1 leads later from our personal phones.

Earlier I was using another system that was based on asterisk. Right now I am using vicidial and this is what I have noticed and the problem I am facing, along with the process which I know.

1Vicidial places call in a bunch of 10(8 remote agent with 1.3 AutoDial Level). So, 2 of them will be returned back with disposition Channel Unavailable. Other 8 will start ringing. 2 of them will be returned is NA(Not Available) with in 5 seconds. That leaves 6 calls dialing. 3-4 will answers, some with reject and some will not pickup. I am having dialout time of 35 secs(10secs of alerting+ ringing time which I wish to use). This all completed in a minute. My average call time of all disposition is around 5-6 seconds. Then here's the issues. Till the start of next minute, i.e. 10:01:00 + 1 minute, all channel will remains idle. I have noticed that in asterisk cli too. For my usage, as I said average call time is 5-6 seconds. So this takes a hit in dialer efficiency. Posting asterisk cli of real-time.

Posting asterisk cli in next message as I was reaching 60,000 character limit.
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby dinstar » Thu Nov 25, 2021 1:12 am

Code: Select all
[Nov 25 11:33:58] Asterisk 13.38.2-vici, Copyright (C) 1999 - 2014, Digium, Inc. and others.
[Nov 25 11:33:58] Created by Mark Spencer <markster@digium.com>
[Nov 25 11:33:58] Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
[Nov 25 11:33:58] This is free software, with components licensed under the GNU General Public
[Nov 25 11:33:58] License version 2 and other licenses; you are welcome to redistribute it under
[Nov 25 11:33:58] certain conditions. Type 'core show license' for details.
[Nov 25 11:33:58] =========================================================================
[Nov 25 11:33:58] Please note that this version of Asterisk no longer receives bug fixes.
[Nov 25 11:33:58] Consult the following URL for Asterisk version support status information:
[Nov 25 11:33:58] https://wiki.asterisk.org/wiki/display/AST/Asterisk+Versions
[Nov 25 11:33:58] =========================================================================
[Nov 25 11:33:58] Connected to Asterisk 13.38.2-vici currently running on vicibox9 (pid = 2379)
[Nov 25 11:34:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:02]     -- Called 09998521607@default
[Nov 25 11:34:02]     -- Executing [09998521607@default:1] AGI("Local/09998521607@default-00003774;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:02]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:02]     -- <Local/09998521607@default-00003774;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:02]     -- Executing [09998521607@default:2] Dial("Local/09998521607@default-00003774;2", "SIP/09998521607@dinstar,,tTo") in new stack
[Nov 25 11:34:02]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:02]     -- Called SIP/09998521607@dinstar
[Nov 25 11:34:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:02]     -- Called 09428122891@default
[Nov 25 11:34:02]     -- Executing [09428122891@default:1] AGI("Local/09428122891@default-00003775;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:02]        > 0x7f1e98017a80 -- Strict RTP learning after remote address set to: 192.168.1.253:8012
[Nov 25 11:34:02]     -- SIP/dinstar-00003774 is making progress passing it to Local/09998521607@default-00003774;2
[Nov 25 11:34:02]     -- Local/09998521607@default-00003774;1 is making progress
[Nov 25 11:34:02]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:02]     -- <Local/09428122891@default-00003775;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:02]     -- Executing [09428122891@default:2] Dial("Local/09428122891@default-00003775;2", "SIP/09428122891@dinstar,,tTo") in new stack
[Nov 25 11:34:02]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:02]     -- Called SIP/09428122891@dinstar
[Nov 25 11:34:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:02]     -- Called 09924611510@default
[Nov 25 11:34:02]     -- Executing [09924611510@default:1] AGI("Local/09924611510@default-00003776;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:02]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:02]     -- <Local/09924611510@default-00003776;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:02]     -- Executing [09924611510@default:2] Dial("Local/09924611510@default-00003776;2", "SIP/09924611510@dinstar,,tTo") in new stack
[Nov 25 11:34:02]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:02]     -- Called SIP/09924611510@dinstar
[Nov 25 11:34:03]        > 0x7f1e98017a80 -- Strict RTP switching to RTP target address 192.168.1.253:8012 as source
[Nov 25 11:34:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:03]     -- Called 09909953665@default
[Nov 25 11:34:03]     -- Executing [09909953665@default:1] AGI("Local/09909953665@default-00003777;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:03]     -- <Local/09909953665@default-00003777;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:03]     -- Executing [09909953665@default:2] Dial("Local/09909953665@default-00003777;2", "SIP/09909953665@dinstar,,tTo") in new stack
[Nov 25 11:34:03]        > 0x7f1e9c015070 -- Strict RTP learning after remote address set to: 192.168.1.253:8016
[Nov 25 11:34:03]     -- SIP/dinstar-00003775 is making progress passing it to Local/09428122891@default-00003775;2
[Nov 25 11:34:03]     -- Local/09428122891@default-00003775;1 is making progress
[Nov 25 11:34:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:03]     -- Called SIP/09909953665@dinstar
[Nov 25 11:34:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:03]     -- Called 09723463795@default
[Nov 25 11:34:03]     -- Executing [09723463795@default:1] AGI("Local/09723463795@default-00003778;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:03]     -- <Local/09723463795@default-00003778;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:03]     -- Executing [09723463795@default:2] Dial("Local/09723463795@default-00003778;2", "SIP/09723463795@dinstar,,tTo") in new stack
[Nov 25 11:34:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:03]     -- Called SIP/09723463795@dinstar
[Nov 25 11:34:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:03]     -- Called 09824976162@default
[Nov 25 11:34:03]     -- Executing [09824976162@default:1] AGI("Local/09824976162@default-00003779;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:03]     -- <Local/09824976162@default-00003779;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:03]     -- Executing [09824976162@default:2] Dial("Local/09824976162@default-00003779;2", "SIP/09824976162@dinstar,,tTo") in new stack
[Nov 25 11:34:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:03]     -- Called SIP/09824976162@dinstar
[Nov 25 11:34:03]     -- Called 09825335205@default
[Nov 25 11:34:03]     -- Executing [09825335205@default:1] AGI("Local/09825335205@default-0000377a;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:03]     -- <Local/09825335205@default-0000377a;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:03]     -- Executing [09825335205@default:2] Dial("Local/09825335205@default-0000377a;2", "SIP/09825335205@dinstar,,tTo") in new stack
[Nov 25 11:34:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:03]     -- Called SIP/09825335205@dinstar
[Nov 25 11:34:03]        > 0x7f1eb006bb80 -- Strict RTP learning after remote address set to: 192.168.1.253:8020
[Nov 25 11:34:03]     -- SIP/dinstar-00003776 is making progress passing it to Local/09924611510@default-00003776;2
[Nov 25 11:34:03]     -- Local/09924611510@default-00003776;1 is making progress
[Nov 25 11:34:03]        > 0x7f1e9c015070 -- Strict RTP switching to RTP target address 192.168.1.253:8016 as source
[Nov 25 11:34:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:03]     -- Called 09099911273@default
[Nov 25 11:34:03]     -- Executing [09099911273@default:1] AGI("Local/09099911273@default-0000377b;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:03]     -- Executing [09429440429@default:1] AGI("Local/09429440429@default-0000377c;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:03]     -- Called 09429440429@default
[Nov 25 11:34:03]     -- <Local/09099911273@default-0000377b;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:03]     -- Executing [09099911273@default:2] Dial("Local/09099911273@default-0000377b;2", "SIP/09099911273@dinstar,,tTo") in new stack
[Nov 25 11:34:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:03]     -- Called SIP/09099911273@dinstar
[Nov 25 11:34:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:03]     -- <Local/09429440429@default-0000377c;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:03]     -- Executing [09429440429@default:2] Dial("Local/09429440429@default-0000377c;2", "SIP/09429440429@dinstar,,tTo") in new stack
[Nov 25 11:34:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:34:03]     -- Called SIP/09429440429@dinstar
[Nov 25 11:34:03]        > 0x7f1eb006bb80 -- Strict RTP switching to RTP target address 192.168.1.253:8020 as source
[Nov 25 11:34:03]        > 0x7f1eb4010060 -- Strict RTP learning after remote address set to: 192.168.1.253:8024
[Nov 25 11:34:03]     -- SIP/dinstar-00003777 is making progress passing it to Local/09909953665@default-00003777;2
[Nov 25 11:34:03]     -- Local/09909953665@default-00003777;1 is making progress
[Nov 25 11:34:03]        > 0x7f1eb4010060 -- Strict RTP switching to RTP target address 192.168.1.253:8024 as source
[Nov 25 11:34:03]        > 0x7f1ec402ba10 -- Strict RTP learning after remote address set to: 192.168.1.253:8028
[Nov 25 11:34:03]     -- SIP/dinstar-00003778 is making progress passing it to Local/09723463795@default-00003778;2
[Nov 25 11:34:03]     -- Local/09723463795@default-00003778;1 is making progress
[Nov 25 11:34:03]        > 0x7f1e48023190 -- Strict RTP learning after remote address set to: 192.168.1.253:8000
[Nov 25 11:34:03]     -- SIP/dinstar-00003779 is making progress passing it to Local/09824976162@default-00003779;2
[Nov 25 11:34:03]     -- Local/09824976162@default-00003779;1 is making progress
[Nov 25 11:34:03]        > 0x7f1ec402ba10 -- Strict RTP switching to RTP target address 192.168.1.253:8028 as source
[Nov 25 11:34:03]        > 0x7f1e4c00cca0 -- Strict RTP learning after remote address set to: 192.168.1.253:8004
[Nov 25 11:34:03]     -- SIP/dinstar-0000377a is making progress passing it to Local/09825335205@default-0000377a;2
[Nov 25 11:34:03]     -- Local/09825335205@default-0000377a;1 is making progress
[Nov 25 11:34:03]        > 0x7f1e48023190 -- Strict RTP switching to RTP target address 192.168.1.253:8000 as source
[Nov 25 11:34:03]        > 0x7f1e4c00cca0 -- Strict RTP switching to RTP target address 192.168.1.253:8004 as source
[Nov 25 11:34:03]        > 0x7f1e64015380 -- Strict RTP learning after remote address set to: 192.168.1.253:8008
[Nov 25 11:34:03]     -- SIP/dinstar-0000377b is making progress passing it to Local/09099911273@default-0000377b;2
[Nov 25 11:34:03]     -- Local/09099911273@default-0000377b;1 is making progress
[Nov 25 11:34:03]        > 0x7f1e64015380 -- Strict RTP switching to RTP target address 192.168.1.253:8008 as source
[Nov 25 11:34:03]     -- Got SIP response 503 "Service Unavailable" back from 192.168.1.253:5060
[Nov 25 11:34:03]     -- SIP/dinstar-0000377c is circuit-busy
[Nov 25 11:34:03]   == Everyone is busy/congested at this time (1:0/1/0)
[Nov 25 11:34:03]     -- Executing [09429440429@default:3] Hangup("Local/09429440429@default-0000377c;2", "") in new stack
[Nov 25 11:34:03]   == Spawn extension (default, 09429440429, 3) exited non-zero on 'Local/09429440429@default-0000377c;2'
[Nov 25 11:34:03]     -- Executing [h@default:1] AGI("Local/09429440429@default-0000377c;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----34-----CONGESTION---------------SIP 503 Service Unavailable)") in new stack
[Nov 25 11:34:03]     -- <Local/09429440429@default-0000377c;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----34-----CONGESTION---------------SIP 503 Service Unavailable) completed, returning 0
[Nov 25 11:34:03]     -- SIP/dinstar-00003778 is making progress passing it to Local/09723463795@default-00003778;2
[Nov 25 11:34:03]     -- Local/09723463795@default-00003778;1 is making progress
[Nov 25 11:34:03]     -- SIP/dinstar-00003779 is making progress passing it to Local/09824976162@default-00003779;2
[Nov 25 11:34:03]     -- Local/09824976162@default-00003779;1 is making progress
[Nov 25 11:34:03]     -- SIP/dinstar-0000377a is making progress passing it to Local/09825335205@default-0000377a;2
[Nov 25 11:34:03]     -- Local/09825335205@default-0000377a;1 is making progress
[Nov 25 11:34:03]     -- SIP/dinstar-0000377b is making progress passing it to Local/09099911273@default-0000377b;2
[Nov 25 11:34:03]     -- Local/09099911273@default-0000377b;1 is making progress
[Nov 25 11:34:04]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:07]        > 0x7f1e98017a80 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8012
[Nov 25 11:34:08]        > 0x7f1e9c015070 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8016
[Nov 25 11:34:08]        > 0x7f1eb006bb80 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8020
[Nov 25 11:34:08]        > 0x7f1eb4010060 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8024
[Nov 25 11:34:08]        > 0x7f1ec402ba10 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8028
[Nov 25 11:34:08]        > 0x7f1e48023190 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8000
[Nov 25 11:34:08]        > 0x7f1e4c00cca0 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8004
[Nov 25 11:34:08]        > 0x7f1e64015380 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8008
[Nov 25 11:34:14]     -- SIP/dinstar-0000377b answered Local/09099911273@default-0000377b;2
[Nov 25 11:34:14]     -- Local/09099911273@default-0000377b;1 answered
[Nov 25 11:34:14]     -- Channel SIP/dinstar-0000377b joined 'simple_bridge' basic-bridge <e02bf662-990a-4a9f-9e77-52db284dd3de>
[Nov 25 11:34:14]     -- Executing [138366@default:1] AGI("Local/09099911273@default-0000377b;1", "agi-VDAD_local_optimize.agi,V1251134020000041588") in new stack
[Nov 25 11:34:14]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:34:14]     -- Channel Local/09099911273@default-0000377b;2 joined 'simple_bridge' basic-bridge <e02bf662-990a-4a9f-9e77-52db284dd3de>
[Nov 25 11:34:14]     -- <Local/09099911273@default-0000377b;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:34:14]     -- Executing [138366@default:2] Wait("Local/09099911273@default-0000377b;1", "2") in new stack
[Nov 25 11:34:14]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:14]     -- Channel SIP/dinstar-0000377b left 'simple_bridge' basic-bridge <e02bf662-990a-4a9f-9e77-52db284dd3de>
[Nov 25 11:34:14]     -- Executing [8366@default:1] AGI("SIP/dinstar-0000377b", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:14]     -- Channel Local/09099911273@default-0000377b;2 left 'simple_bridge' basic-bridge <e02bf662-990a-4a9f-9e77-52db284dd3de>
[Nov 25 11:34:14]   == Spawn extension (default, 09099911273, 2) exited non-zero on 'Local/09099911273@default-0000377b;2'
[Nov 25 11:34:14]     -- Executing [h@default:1] AGI("Local/09099911273@default-0000377b;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0-----SIP 200 OK)") in new stack
[Nov 25 11:34:14]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:14]     -- <SIP/dinstar-0000377b>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:14]     -- Executing [8366@default:2] AGI("SIP/dinstar-0000377b", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251134020000041588") in new stack
[Nov 25 11:34:14]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:34:14]     -- <Local/09099911273@default-0000377b;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:34:14]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09099911273@default-0000377b;1'
[Nov 25 11:34:14] WARNING[18788][C-00005320]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:34:14]     -- Executing [h@default:1] AGI("Local/09099911273@default-0000377b;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:34:14]     -- <Local/09099911273@default-0000377b;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:34:15]     -- <SIP/dinstar-0000377b> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:34:15]     -- <SIP/dinstar-0000377b> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4247 __ast_read: DTMF begin '4' received on SIP/dinstar-00003775
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4258 __ast_read: DTMF begin passthrough '4' on SIP/dinstar-00003775
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4133 __ast_read: DTMF end '4' received on SIP/dinstar-00003775, duration 65 ms
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4184 __ast_read: DTMF end accepted with begin '4' on SIP/dinstar-00003775
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4199 __ast_read: DTMF end '4' detected to have actual duration 59 on the wire, emulation will be triggered on SIP/dinstar-00003775
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4206 __ast_read: DTMF end '4' has duration 59 but want minimum 80, emulating on SIP/dinstar-00003775
[Nov 25 11:34:15] DTMF[18758][C-00005318]: channel.c:4281 __ast_read: DTMF end emulation of '4' queued on SIP/dinstar-00003775
[Nov 25 11:34:15]     -- Got SIP response 503 "Service Unavailable" back from 192.168.1.253:5060
[Nov 25 11:34:15]     -- SIP/dinstar-00003779 is circuit-busy
[Nov 25 11:34:15]   == Everyone is busy/congested at this time (1:0/1/0)
[Nov 25 11:34:15]     -- Executing [09824976162@default:3] Hangup("Local/09824976162@default-00003779;2", "") in new stack
[Nov 25 11:34:15]   == Spawn extension (default, 09824976162, 3) exited non-zero on 'Local/09824976162@default-00003779;2'
[Nov 25 11:34:15]     -- Executing [h@default:1] AGI("Local/09824976162@default-00003779;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----34-----CONGESTION---------------SIP 503 Service Unavailable)") in new stack
[Nov 25 11:34:15]     -- <Local/09824976162@default-00003779;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----34-----CONGESTION---------------SIP 503 Service Unavailable) completed, returning 0
[Nov 25 11:34:15]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:15]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:16]     -- <SIP/dinstar-0000377b>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:34:16]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-0000377b", "") in new stack
[Nov 25 11:34:16]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-0000377b", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:34:16]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:34:16]     -- <SIP/dinstar-0000377b>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:34:16]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-0000377b", "INVCOUNT=0") in new stack
[Nov 25 11:34:16]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-0000377b", "IDFCBL") in new stack
[Nov 25 11:34:16]     -- <SIP/dinstar-0000377b> Playing 'IDFCBL.slin' (language 'en')
[Nov 25 11:34:16]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4247 __ast_read: DTMF begin '0' received on SIP/dinstar-00003775
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4258 __ast_read: DTMF begin passthrough '0' on SIP/dinstar-00003775
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4133 __ast_read: DTMF end '0' received on SIP/dinstar-00003775, duration 25 ms
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4184 __ast_read: DTMF end accepted with begin '0' on SIP/dinstar-00003775
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4199 __ast_read: DTMF end '0' detected to have actual duration 19 on the wire, emulation will be triggered on SIP/dinstar-00003775
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4206 __ast_read: DTMF end '0' has duration 19 but want minimum 80, emulating on SIP/dinstar-00003775
[Nov 25 11:34:16] DTMF[18758][C-00005318]: channel.c:4334 __ast_read: DTMF end emulation of '0' queued on SIP/dinstar-00003775
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4247 __ast_read: DTMF begin '4' received on SIP/dinstar-00003775
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4258 __ast_read: DTMF begin passthrough '4' on SIP/dinstar-00003775
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4133 __ast_read: DTMF end '4' received on SIP/dinstar-00003775, duration 46 ms
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4184 __ast_read: DTMF end accepted with begin '4' on SIP/dinstar-00003775
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4199 __ast_read: DTMF end '4' detected to have actual duration 40 on the wire, emulation will be triggered on SIP/dinstar-00003775
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4206 __ast_read: DTMF end '4' has duration 40 but want minimum 80, emulating on SIP/dinstar-00003775
[Nov 25 11:34:23] DTMF[18758][C-00005318]: channel.c:4281 __ast_read: DTMF end emulation of '4' queued on SIP/dinstar-00003775
[Nov 25 11:34:23]     -- SIP/dinstar-00003774 answered Local/09998521607@default-00003774;2
[Nov 25 11:34:23]     -- Local/09998521607@default-00003774;1 answered
[Nov 25 11:34:23]     -- Executing [138366@default:1] AGI("Local/09998521607@default-00003774;1", "agi-VDAD_local_optimize.agi,V1251134020000183956") in new stack
[Nov 25 11:34:23]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:34:23]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:23]     -- Channel SIP/dinstar-00003774 joined 'simple_bridge' basic-bridge <a3c37287-45cc-4220-a3b4-ff3275b14b88>
[Nov 25 11:34:23]     -- Channel Local/09998521607@default-00003774;2 joined 'simple_bridge' basic-bridge <a3c37287-45cc-4220-a3b4-ff3275b14b88>
[Nov 25 11:34:23]     -- <Local/09998521607@default-00003774;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:34:23]     -- Executing [138366@default:2] Wait("Local/09998521607@default-00003774;1", "2") in new stack
[Nov 25 11:34:23]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:23]     -- Channel SIP/dinstar-00003774 left 'simple_bridge' basic-bridge <a3c37287-45cc-4220-a3b4-ff3275b14b88>
[Nov 25 11:34:23]     -- Executing [8366@default:1] AGI("SIP/dinstar-00003774", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:23]     -- Channel Local/09998521607@default-00003774;2 left 'simple_bridge' basic-bridge <a3c37287-45cc-4220-a3b4-ff3275b14b88>
[Nov 25 11:34:23]   == Spawn extension (default, 09998521607, 2) exited non-zero on 'Local/09998521607@default-00003774;2'
[Nov 25 11:34:23]     -- Executing [h@default:1] AGI("Local/09998521607@default-00003774;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----0-----SIP 200 OK)") in new stack
[Nov 25 11:34:23]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:23]     -- <SIP/dinstar-00003774>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:23]     -- Executing [8366@default:2] AGI("SIP/dinstar-00003774", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251134020000183956") in new stack
[Nov 25 11:34:23]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:34:23]     -- <Local/09998521607@default-00003774;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:34:23]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09998521607@default-00003774;1'
[Nov 25 11:34:23] WARNING[18754][C-00005321]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:34:23]     -- Executing [h@default:1] AGI("Local/09998521607@default-00003774;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:34:23]     -- <Local/09998521607@default-00003774;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:34:23]     -- SIP/dinstar-00003778 answered Local/09723463795@default-00003778;2
[Nov 25 11:34:23]     -- Local/09723463795@default-00003778;1 answered
[Nov 25 11:34:23]     -- Channel SIP/dinstar-00003778 joined 'simple_bridge' basic-bridge <39ab6cbf-c8a0-4656-afb0-8d1c4467b085>
[Nov 25 11:34:23]     -- Executing [138366@default:1] AGI("Local/09723463795@default-00003778;1", "agi-VDAD_local_optimize.agi,V1251134020000092727") in new stack
[Nov 25 11:34:23]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:34:23]     -- Channel Local/09723463795@default-00003778;2 joined 'simple_bridge' basic-bridge <39ab6cbf-c8a0-4656-afb0-8d1c4467b085>
[Nov 25 11:34:23]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:23]     -- <Local/09723463795@default-00003778;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:34:23]     -- Executing [138366@default:2] Wait("Local/09723463795@default-00003778;1", "2") in new stack
[Nov 25 11:34:23]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:23]     -- Channel SIP/dinstar-00003778 left 'simple_bridge' basic-bridge <39ab6cbf-c8a0-4656-afb0-8d1c4467b085>
[Nov 25 11:34:23]     -- Channel Local/09723463795@default-00003778;2 left 'simple_bridge' basic-bridge <39ab6cbf-c8a0-4656-afb0-8d1c4467b085>
[Nov 25 11:34:23]     -- Executing [8366@default:1] AGI("SIP/dinstar-00003778", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:23]   == Spawn extension (default, 09723463795, 2) exited non-zero on 'Local/09723463795@default-00003778;2'
[Nov 25 11:34:23]     -- Executing [h@default:1] AGI("Local/09723463795@default-00003778;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----0-----SIP 200 OK)") in new stack
[Nov 25 11:34:23]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:23]     -- <SIP/dinstar-00003778>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:23]     -- Executing [8366@default:2] AGI("SIP/dinstar-00003778", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251134020000092727") in new stack
[Nov 25 11:34:23]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:34:23]     -- <Local/09723463795@default-00003778;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:34:23]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09723463795@default-00003778;1'
[Nov 25 11:34:23] WARNING[18774][C-00005322]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:34:23]     -- Executing [h@default:1] AGI("Local/09723463795@default-00003778;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:34:23]     -- <Local/09723463795@default-00003778;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:34:23]     -- <SIP/dinstar-00003774> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:34:23]     -- <SIP/dinstar-00003774> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:34:23]     -- <SIP/dinstar-00003778> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:34:23]     -- <SIP/dinstar-00003778> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:34:23] WARNING[2493][C-0000531d]: chan_sip.c:24232 handle_response_invite: Received response: "Forbidden" from '"V1251134020000129277" <sip:9123458975@192.168.1.254>;tag=as0bf2ee7f'
[Nov 25 11:34:23]   == Everyone is busy/congested at this time (1:0/0/1)
[Nov 25 11:34:23]     -- Executing [09825335205@default:3] Hangup("Local/09825335205@default-0000377a;2", "") in new stack
[Nov 25 11:34:23]   == Spawn extension (default, 09825335205, 3) exited non-zero on 'Local/09825335205@default-0000377a;2'
[Nov 25 11:34:23]     -- Executing [h@default:1] AGI("Local/09825335205@default-0000377a;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----21-----CHANUNAVAIL---------------SIP 403 Forbidden)") in new stack
[Nov 25 11:34:23]     -- <Local/09825335205@default-0000377a;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----21-----CHANUNAVAIL---------------SIP 403 Forbidden) completed, returning 0
[Nov 25 11:34:23]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:24]     -- SIP/dinstar-00003776 answered Local/09924611510@default-00003776;2
[Nov 25 11:34:24]     -- Local/09924611510@default-00003776;1 answered
[Nov 25 11:34:24]     -- Channel SIP/dinstar-00003776 joined 'simple_bridge' basic-bridge <5b24b981-dcc3-4fb1-8578-a036a7d2dbc0>
[Nov 25 11:34:24]     -- Executing [138366@default:1] AGI("Local/09924611510@default-00003776;1", "agi-VDAD_local_optimize.agi,V1251134020000166651") in new stack
[Nov 25 11:34:24]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:34:24]     -- Channel Local/09924611510@default-00003776;2 joined 'simple_bridge' basic-bridge <5b24b981-dcc3-4fb1-8578-a036a7d2dbc0>
[Nov 25 11:34:24]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:24]     -- <Local/09924611510@default-00003776;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:34:24]     -- Executing [138366@default:2] Wait("Local/09924611510@default-00003776;1", "2") in new stack
[Nov 25 11:34:24]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:34:24]     -- Channel SIP/dinstar-00003776 left 'simple_bridge' basic-bridge <5b24b981-dcc3-4fb1-8578-a036a7d2dbc0>
[Nov 25 11:34:24]     -- Executing [8366@default:1] AGI("SIP/dinstar-00003776", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:34:24]     -- Channel Local/09924611510@default-00003776;2 left 'simple_bridge' basic-bridge <5b24b981-dcc3-4fb1-8578-a036a7d2dbc0>
[Nov 25 11:34:24]   == Spawn extension (default, 09924611510, 2) exited non-zero on 'Local/09924611510@default-00003776;2'
[Nov 25 11:34:24]     -- Executing [h@default:1] AGI("Local/09924611510@default-00003776;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----21-----0-----SIP 200 OK)") in new stack
[Nov 25 11:34:24]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:34:24]     -- <SIP/dinstar-00003776>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:34:24]     -- Executing [8366@default:2] AGI("SIP/dinstar-00003776", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251134020000166651") in new stack
[Nov 25 11:34:24]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:34:24]     -- <Local/09924611510@default-00003776;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----21-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:34:24]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09924611510@default-00003776;1'
[Nov 25 11:34:24] WARNING[18762][C-00005323]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:34:24]     -- Executing [h@default:1] AGI("Local/09924611510@default-00003776;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:34:24]     -- <Local/09924611510@default-00003776;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:34:24]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:24]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:24]     -- <SIP/dinstar-00003776> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:34:24]     -- <SIP/dinstar-00003776> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:34:24]     -- <SIP/dinstar-00003774>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-00003774", "") in new stack
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-00003774", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:34:24]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:34:24]     -- <SIP/dinstar-00003774>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-00003774", "INVCOUNT=0") in new stack
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-00003774", "IDFCBL") in new stack
[Nov 25 11:34:24]     -- <SIP/dinstar-00003774> Playing 'IDFCBL.slin' (language 'en')
[Nov 25 11:34:24]     -- <SIP/dinstar-00003778>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-00003778", "") in new stack
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-00003778", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:34:24]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:34:24]     -- <SIP/dinstar-00003778>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-00003778", "INVCOUNT=0") in new stack
[Nov 25 11:34:24]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-00003778", "IDFCBL") in new stack
[Nov 25 11:34:24]     -- <SIP/dinstar-00003778> Playing 'IDFCBL.slin' (language 'en')
[Nov 25 11:34:25]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:25]     -- <SIP/dinstar-00003776>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:34:25]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-00003776", "") in new stack
[Nov 25 11:34:25]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-00003776", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:34:25]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:34:25]     -- <SIP/dinstar-00003776>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:34:25]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-00003776", "INVCOUNT=0") in new stack
[Nov 25 11:34:25]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-00003776", "IDFCBL") in new stack
[Nov 25 11:34:25]     -- <SIP/dinstar-00003776> Playing 'IDFCBL.slin' (language 'en')
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby dinstar » Thu Nov 25, 2021 1:13 am

Code: Select all
[Nov 25 11:34:26]   == Everyone is busy/congested at this time (1:0/0/1)
[Nov 25 11:34:26]     -- Executing [09428122891@default:3] Hangup("Local/09428122891@default-00003775;2", "") in new stack
[Nov 25 11:34:26]   == Spawn extension (default, 09428122891, 3) exited non-zero on 'Local/09428122891@default-00003775;2'
[Nov 25 11:34:26]     -- Executing [h@default:1] AGI("Local/09428122891@default-00003775;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----18-----CHANUNAVAIL---------------SIP 408 Request Timeout)") in new stack
[Nov 25 11:34:26]     -- <Local/09428122891@default-00003775;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----18-----CHANUNAVAIL---------------SIP 408 Request Timeout) completed, returning 0
[Nov 25 11:34:26]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:31]   == Spawn extension (IDFC-BL, s, 4) exited non-zero on 'SIP/dinstar-00003778'
[Nov 25 11:34:31]     -- Executing [h@IDFC-BL:1] AGI("SIP/dinstar-00003778", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Nov 25 11:34:31]     -- <SIP/dinstar-00003778>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, returning 0
[Nov 25 11:34:31]     -- Executing [s@IDFC-BL:5] WaitExten("SIP/dinstar-0000377b", "15") in new stack
[Nov 25 11:34:35]   == Spawn extension (IDFC-BL, s, 5) exited non-zero on 'SIP/dinstar-0000377b'
[Nov 25 11:34:35]     -- Executing [h@IDFC-BL:1] AGI("SIP/dinstar-0000377b", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Nov 25 11:34:35]     -- <SIP/dinstar-0000377b>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, returning 0
[Nov 25 11:34:35]   == Spawn extension (IDFC-BL, s, 4) exited non-zero on 'SIP/dinstar-00003776'
[Nov 25 11:34:35]     -- Executing [h@IDFC-BL:1] AGI("SIP/dinstar-00003776", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Nov 25 11:34:35]     -- <SIP/dinstar-00003776>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, returning 0
[Nov 25 11:34:39]   == Spawn extension (default, 09909953665, 2) exited non-zero on 'Local/09909953665@default-00003777;2'
[Nov 25 11:34:39]     -- Executing [h@default:1] AGI("Local/09909953665@default-00003777;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress)") in new stack
[Nov 25 11:34:39]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:34:39]     -- <Local/09909953665@default-00003777;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress) completed, returning 0
[Nov 25 11:34:40]     -- Executing [s@IDFC-BL:5] WaitExten("SIP/dinstar-00003774", "15") in new stack
[Nov 25 11:34:47]   == Spawn extension (IDFC-BL, s, 5) exited non-zero on 'SIP/dinstar-00003774'
[Nov 25 11:34:47]     -- Executing [h@IDFC-BL:1] AGI("SIP/dinstar-00003774", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Nov 25 11:34:47]     -- <SIP/dinstar-00003774>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, returning 0
[Nov 25 11:35:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:03]     -- Called 09824650302@default
[Nov 25 11:35:03]     -- Executing [09824650302@default:1] AGI("Local/09824650302@default-0000377d;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:35:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:35:03]     -- <Local/09824650302@default-0000377d;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:35:03]     -- Executing [09824650302@default:2] Dial("Local/09824650302@default-0000377d;2", "SIP/09824650302@dinstar,,tTo") in new stack
[Nov 25 11:35:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:35:03]     -- Called SIP/09824650302@dinstar
[Nov 25 11:35:03]        > 0x7f1eb006bb80 -- Strict RTP learning after remote address set to: 192.168.1.253:8012
[Nov 25 11:35:03]     -- SIP/dinstar-0000377d is making progress passing it to Local/09824650302@default-0000377d;2
[Nov 25 11:35:03]     -- Local/09824650302@default-0000377d;1 is making progress
[Nov 25 11:35:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:03]     -- Called 08866001331@default
[Nov 25 11:35:03]     -- Executing [08866001331@default:1] AGI("Local/08866001331@default-0000377e;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:35:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:35:03]     -- <Local/08866001331@default-0000377e;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:35:03]     -- Executing [08866001331@default:2] Dial("Local/08866001331@default-0000377e;2", "SIP/08866001331@dinstar,,tTo") in new stack
[Nov 25 11:35:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:35:03]     -- Called SIP/08866001331@dinstar
[Nov 25 11:35:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:03]     -- Called 09824046452@default
[Nov 25 11:35:03]     -- Executing [09824046452@default:1] AGI("Local/09824046452@default-0000377f;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:35:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:35:03]     -- <Local/09824046452@default-0000377f;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:35:03]     -- Executing [09824046452@default:2] Dial("Local/09824046452@default-0000377f;2", "SIP/09824046452@dinstar,,tTo") in new stack
[Nov 25 11:35:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:35:03]     -- Called SIP/09824046452@dinstar
[Nov 25 11:35:03]        > 0x7f1eb006bb80 -- Strict RTP switching to RTP target address 192.168.1.253:8012 as source
[Nov 25 11:35:03]        > 0x7f1eb4010060 -- Strict RTP learning after remote address set to: 192.168.1.253:8016
[Nov 25 11:35:03]     -- SIP/dinstar-0000377e is making progress passing it to Local/08866001331@default-0000377e;2
[Nov 25 11:35:03]     -- Local/08866001331@default-0000377e;1 is making progress
[Nov 25 11:35:03]        > 0x7f1eb4010060 -- Strict RTP switching to RTP target address 192.168.1.253:8016 as source
[Nov 25 11:35:03]        > 0x7f1ec402ba10 -- Strict RTP learning after remote address set to: 192.168.1.253:8020
[Nov 25 11:35:03]     -- SIP/dinstar-0000377f is making progress passing it to Local/09824046452@default-0000377f;2
[Nov 25 11:35:03]     -- Local/09824046452@default-0000377f;1 is making progress
[Nov 25 11:35:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:03]     -- Called 09427050670@default
[Nov 25 11:35:03]     -- Executing [09427050670@default:1] AGI("Local/09427050670@default-00003780;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:35:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:35:03]     -- <Local/09427050670@default-00003780;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:35:03]     -- Executing [09427050670@default:2] Dial("Local/09427050670@default-00003780;2", "SIP/09427050670@dinstar,,tTo") in new stack
[Nov 25 11:35:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:35:03]     -- Called SIP/09427050670@dinstar
[Nov 25 11:35:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:03]     -- Called 09824094892@default
[Nov 25 11:35:03]     -- Executing [09824094892@default:1] AGI("Local/09824094892@default-00003781;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:35:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:35:03]     -- <Local/09824094892@default-00003781;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:35:03]     -- Executing [09824094892@default:2] Dial("Local/09824094892@default-00003781;2", "SIP/09824094892@dinstar,,tTo") in new stack
[Nov 25 11:35:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:35:03]     -- Called SIP/09824094892@dinstar
[Nov 25 11:35:03]        > 0x7f1e48023190 -- Strict RTP learning after remote address set to: 192.168.1.253:8024
[Nov 25 11:35:03]     -- SIP/dinstar-00003780 is making progress passing it to Local/09427050670@default-00003780;2
[Nov 25 11:35:03]     -- Local/09427050670@default-00003780;1 is making progress
[Nov 25 11:35:03]        > 0x7f1ec402ba10 -- Strict RTP switching to RTP target address 192.168.1.253:8020 as source
[Nov 25 11:35:03]        > 0x7f1e48023190 -- Strict RTP switching to RTP target address 192.168.1.253:8024 as source
[Nov 25 11:35:03]        > 0x7f1e4c00cca0 -- Strict RTP learning after remote address set to: 192.168.1.253:8028
[Nov 25 11:35:03]     -- SIP/dinstar-00003781 is making progress passing it to Local/09824094892@default-00003781;2
[Nov 25 11:35:03]     -- Local/09824094892@default-00003781;1 is making progress
[Nov 25 11:35:03]        > 0x7f1e4c00cca0 -- Strict RTP switching to RTP target address 192.168.1.253:8028 as source
[Nov 25 11:35:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:08]        > 0x7f1eb006bb80 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8012
[Nov 25 11:35:08]        > 0x7f1eb4010060 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8016
[Nov 25 11:35:08]        > 0x7f1ec402ba10 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8020
[Nov 25 11:35:08]        > 0x7f1e48023190 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8024
[Nov 25 11:35:08]        > 0x7f1e4c00cca0 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8028
[Nov 25 11:35:13]     -- SIP/dinstar-0000377f answered Local/09824046452@default-0000377f;2
[Nov 25 11:35:13]     -- Local/09824046452@default-0000377f;1 answered
[Nov 25 11:35:13]     -- Executing [138366@default:1] AGI("Local/09824046452@default-0000377f;1", "agi-VDAD_local_optimize.agi,V1251135020000105274") in new stack
[Nov 25 11:35:13]     -- Channel SIP/dinstar-0000377f joined 'simple_bridge' basic-bridge <1413de04-db5d-4816-9576-1a6fe658e8d9>
[Nov 25 11:35:13]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:35:13]     -- Channel Local/09824046452@default-0000377f;2 joined 'simple_bridge' basic-bridge <1413de04-db5d-4816-9576-1a6fe658e8d9>
[Nov 25 11:35:13]     -- <Local/09824046452@default-0000377f;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:35:13]     -- Executing [138366@default:2] Wait("Local/09824046452@default-0000377f;1", "2") in new stack
[Nov 25 11:35:13]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:35:13]     -- Channel SIP/dinstar-0000377f left 'simple_bridge' basic-bridge <1413de04-db5d-4816-9576-1a6fe658e8d9>
[Nov 25 11:35:13]     -- Channel Local/09824046452@default-0000377f;2 left 'simple_bridge' basic-bridge <1413de04-db5d-4816-9576-1a6fe658e8d9>
[Nov 25 11:35:13]     -- Executing [8366@default:1] AGI("SIP/dinstar-0000377f", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:35:13]   == Spawn extension (default, 09824046452, 2) exited non-zero on 'Local/09824046452@default-0000377f;2'
[Nov 25 11:35:13]     -- Executing [h@default:1] AGI("Local/09824046452@default-0000377f;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----10-----0-----SIP 200 OK)") in new stack
[Nov 25 11:35:13]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:35:13]     -- <SIP/dinstar-0000377f>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:35:13]     -- Executing [8366@default:2] AGI("SIP/dinstar-0000377f", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251135020000105274") in new stack
[Nov 25 11:35:13]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:35:13]     -- <Local/09824046452@default-0000377f;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----10-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:35:13]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09824046452@default-0000377f;1'
[Nov 25 11:35:13] WARNING[18948][C-00005329]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:35:13]     -- Executing [h@default:1] AGI("Local/09824046452@default-0000377f;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:35:13]     -- <Local/09824046452@default-0000377f;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:35:13]     -- <SIP/dinstar-0000377f> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:35:13]     -- <SIP/dinstar-0000377f> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:35:14]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:14]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:14]     -- <SIP/dinstar-0000377f>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:35:14]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-0000377f", "") in new stack
[Nov 25 11:35:14]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-0000377f", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:35:14]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:35:14]     -- <SIP/dinstar-0000377f>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:35:14]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-0000377f", "INVCOUNT=0") in new stack
[Nov 25 11:35:14]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-0000377f", "IDFCBL") in new stack
[Nov 25 11:35:14]     -- <SIP/dinstar-0000377f> Playing 'IDFCBL.slin' (language 'en')
[Nov 25 11:35:18]   == Spawn extension (IDFC-BL, s, 4) exited non-zero on 'SIP/dinstar-0000377f'
[Nov 25 11:35:18]     -- Executing [h@IDFC-BL:1] AGI("SIP/dinstar-0000377f", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack
[Nov 25 11:35:18]     -- <SIP/dinstar-0000377f>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK) completed, returning 0
[Nov 25 11:35:29]     -- SIP/dinstar-0000377d redirecting info has changed, passing it to Local/09824650302@default-0000377d;2
[Nov 25 11:35:29]     -- SIP/dinstar-0000377d is busy
[Nov 25 11:35:29]   == Everyone is busy/congested at this time (1:1/0/0)
[Nov 25 11:35:29]     -- Executing [09824650302@default:3] Hangup("Local/09824650302@default-0000377d;2", "") in new stack
[Nov 25 11:35:29]   == Spawn extension (default, 09824650302, 3) exited non-zero on 'Local/09824650302@default-0000377d;2'
[Nov 25 11:35:29]     -- Executing [h@default:1] AGI("Local/09824650302@default-0000377d;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----19-----BUSY---------------SIP 480 Temporarily not available)") in new stack
[Nov 25 11:35:29]     -- <Local/09824650302@default-0000377d;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----19-----BUSY---------------SIP 480 Temporarily not available) completed, returning 0
[Nov 25 11:35:29]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:39]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:39]   == Spawn extension (default, 08866001331, 2) exited non-zero on 'Local/08866001331@default-0000377e;2'
[Nov 25 11:35:39]     -- Executing [h@default:1] AGI("Local/08866001331@default-0000377e;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress)") in new stack
[Nov 25 11:35:39]     -- <Local/08866001331@default-0000377e;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress) completed, returning 0
[Nov 25 11:35:39]   == Spawn extension (default, 09824094892, 2) exited non-zero on 'Local/09824094892@default-00003781;2'
[Nov 25 11:35:39]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:39]     -- Executing [h@default:1] AGI("Local/09824094892@default-00003781;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress)") in new stack
[Nov 25 11:35:39]     -- <Local/09824094892@default-00003781;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress) completed, returning 0
[Nov 25 11:35:39]   == Spawn extension (default, 09427050670, 2) exited non-zero on 'Local/09427050670@default-00003780;2'
[Nov 25 11:35:39]     -- Executing [h@default:1] AGI("Local/09427050670@default-00003780;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress)") in new stack
[Nov 25 11:35:39]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:35:39]     -- <Local/09427050670@default-00003780;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL---------------SIP 183 Session Progress) completed, returning 0
[Nov 25 11:36:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]     -- Called 09427949856@default
[Nov 25 11:36:03]     -- Executing [09427949856@default:1] AGI("Local/09427949856@default-00003782;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/09427949856@default-00003782;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [09427949856@default:2] Dial("Local/09427949856@default-00003782;2", "SIP/09427949856@dinstar,,tTo") in new stack
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/09427949856@dinstar
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]        > 0x7f1e7c014a60 -- Strict RTP learning after remote address set to: 192.168.1.253:8000
[Nov 25 11:36:03]     -- SIP/dinstar-00003782 is making progress passing it to Local/09427949856@default-00003782;2
[Nov 25 11:36:03]     -- Local/09427949856@default-00003782;1 is making progress
[Nov 25 11:36:03]     -- Called 09636464396@default
[Nov 25 11:36:03]     -- Executing [09636464396@default:1] AGI("Local/09636464396@default-00003783;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/09636464396@default-00003783;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [09636464396@default:2] Dial("Local/09636464396@default-00003783;2", "SIP/09636464396@dinstar,,tTo") in new stack
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/09636464396@dinstar
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]     -- Called 08511358109@default
[Nov 25 11:36:03]     -- Executing [08511358109@default:1] AGI("Local/08511358109@default-00003784;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/08511358109@default-00003784;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [08511358109@default:2] Dial("Local/08511358109@default-00003784;2", "SIP/08511358109@dinstar,,tTo") in new stack
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/08511358109@dinstar
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]     -- Called 09824583159@default
[Nov 25 11:36:03]     -- Executing [09824583159@default:1] AGI("Local/09824583159@default-00003785;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/09824583159@default-00003785;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [09824583159@default:2] Dial("Local/09824583159@default-00003785;2", "SIP/09824583159@dinstar,,tTo") in new stack
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/09824583159@dinstar
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]     -- Called 09824161793@default
[Nov 25 11:36:03]     -- Executing [09824161793@default:1] AGI("Local/09824161793@default-00003786;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/09824161793@default-00003786;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [09824161793@default:2] Dial("Local/09824161793@default-00003786;2", "SIP/09824161793@dinstar,,tTo") in new stack
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/09824161793@dinstar
[Nov 25 11:36:03]        > 0x7f1e9002b170 -- Strict RTP learning after remote address set to: 192.168.1.253:8004
[Nov 25 11:36:03]     -- SIP/dinstar-00003783 is making progress passing it to Local/09636464396@default-00003783;2
[Nov 25 11:36:03]     -- Local/09636464396@default-00003783;1 is making progress
[Nov 25 11:36:03]        > 0x7f1e7c014a60 -- Strict RTP switching to RTP target address 192.168.1.253:8000 as source
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]     -- Called 09989962377@default
[Nov 25 11:36:03]     -- Executing [09989962377@default:1] AGI("Local/09989962377@default-00003787;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/09989962377@default-00003787;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [09989962377@default:2] Dial("Local/09989962377@default-00003787;2", "SIP/09989962377@dinstar,,tTo") in new stack
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/09989962377@dinstar
[Nov 25 11:36:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:03]     -- Called 09328022615@default
[Nov 25 11:36:03]     -- Executing [09328022615@default:1] AGI("Local/09328022615@default-00003788;2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:03]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:03]     -- <Local/09328022615@default-00003788;2>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:03]     -- Executing [09328022615@default:2] Dial("Local/09328022615@default-00003788;2", "SIP/09328022615@dinstar,,tTo") in new stack
[Nov 25 11:36:03]        > 0x7f1e9002b170 -- Strict RTP switching to RTP target address 192.168.1.253:8004 as source
[Nov 25 11:36:03]   == Using SIP RTP CoS mark 5
[Nov 25 11:36:03]     -- Called SIP/09328022615@dinstar
[Nov 25 11:36:03]        > 0x7f1e94020020 -- Strict RTP learning after remote address set to: 192.168.1.253:8008
[Nov 25 11:36:03]     -- SIP/dinstar-00003784 is making progress passing it to Local/08511358109@default-00003784;2
[Nov 25 11:36:03]     -- Local/08511358109@default-00003784;1 is making progress
[Nov 25 11:36:03]        > 0x7f1e94020020 -- Strict RTP switching to RTP target address 192.168.1.253:8008 as source
[Nov 25 11:36:03]        > 0x7f1ea80252c0 -- Strict RTP learning after remote address set to: 192.168.1.253:8012
[Nov 25 11:36:03]     -- SIP/dinstar-00003785 is making progress passing it to Local/09824583159@default-00003785;2
[Nov 25 11:36:03]     -- Local/09824583159@default-00003785;1 is making progress
[Nov 25 11:36:03]        > 0x7f1eac029460 -- Strict RTP learning after remote address set to: 192.168.1.253:8016
[Nov 25 11:36:03]     -- SIP/dinstar-00003786 is making progress passing it to Local/09824161793@default-00003786;2
[Nov 25 11:36:03]     -- Local/09824161793@default-00003786;1 is making progress
[Nov 25 11:36:03]        > 0x7f1ea80252c0 -- Strict RTP switching to RTP target address 192.168.1.253:8012 as source
[Nov 25 11:36:03]        > 0x7f1ec001dc80 -- Strict RTP learning after remote address set to: 192.168.1.253:8020
[Nov 25 11:36:03]     -- SIP/dinstar-00003787 is making progress passing it to Local/09989962377@default-00003787;2
[Nov 25 11:36:03]     -- Local/09989962377@default-00003787;1 is making progress
[Nov 25 11:36:03]        > 0x7f1eac029460 -- Strict RTP switching to RTP target address 192.168.1.253:8016 as source
[Nov 25 11:36:03]        > 0x7f1ec001dc80 -- Strict RTP switching to RTP target address 192.168.1.253:8020 as source
[Nov 25 11:36:03]        > 0x557d5c4e6b50 -- Strict RTP learning after remote address set to: 192.168.1.253:8024
[Nov 25 11:36:03]     -- SIP/dinstar-00003788 is making progress passing it to Local/09328022615@default-00003788;2
[Nov 25 11:36:03]     -- Local/09328022615@default-00003788;1 is making progress
[Nov 25 11:36:03]        > 0x557d5c4e6b50 -- Strict RTP switching to RTP target address 192.168.1.253:8024 as source
[Nov 25 11:36:03]     -- SIP/dinstar-00003785 is making progress passing it to Local/09824583159@default-00003785;2
[Nov 25 11:36:03]     -- Local/09824583159@default-00003785;1 is making progress
[Nov 25 11:36:03]     -- SIP/dinstar-00003786 is making progress passing it to Local/09824161793@default-00003786;2
[Nov 25 11:36:03]     -- Local/09824161793@default-00003786;1 is making progress
[Nov 25 11:36:03]     -- SIP/dinstar-00003787 is making progress passing it to Local/09989962377@default-00003787;2
[Nov 25 11:36:03]     -- Local/09989962377@default-00003787;1 is making progress
[Nov 25 11:36:03]     -- SIP/dinstar-00003788 is making progress passing it to Local/09328022615@default-00003788;2
[Nov 25 11:36:03]     -- Local/09328022615@default-00003788;1 is making progress
[Nov 25 11:36:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:08]        > 0x7f1e7c014a60 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8000
[Nov 25 11:36:08]        > 0x7f1e9002b170 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8004
[Nov 25 11:36:08]        > 0x7f1e94020020 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8008
[Nov 25 11:36:08]        > 0x7f1ea80252c0 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8012
[Nov 25 11:36:08]        > 0x7f1eac029460 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8016
[Nov 25 11:36:08]        > 0x7f1ec001dc80 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8020
[Nov 25 11:36:08]        > 0x557d5c4e6b50 -- Strict RTP learning complete - Locking on source address 192.168.1.253:8024
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4247 __ast_read: DTMF begin '4' received on SIP/dinstar-00003788
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4258 __ast_read: DTMF begin passthrough '4' on SIP/dinstar-00003788
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4133 __ast_read: DTMF end '4' received on SIP/dinstar-00003788, duration 61 ms
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4184 __ast_read: DTMF end accepted with begin '4' on SIP/dinstar-00003788
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4199 __ast_read: DTMF end '4' detected to have actual duration 60 on the wire, emulation will be triggered on SIP/dinstar-00003788
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4206 __ast_read: DTMF end '4' has duration 60 but want minimum 80, emulating on SIP/dinstar-00003788
[Nov 25 11:36:13] DTMF[19123][C-00005330]: channel.c:4281 __ast_read: DTMF end emulation of '4' queued on SIP/dinstar-00003788
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4247 __ast_read: DTMF begin '4' received on SIP/dinstar-00003783
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4258 __ast_read: DTMF begin passthrough '4' on SIP/dinstar-00003783
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4133 __ast_read: DTMF end '4' received on SIP/dinstar-00003783, duration 46 ms
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4184 __ast_read: DTMF end accepted with begin '4' on SIP/dinstar-00003783
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4199 __ast_read: DTMF end '4' detected to have actual duration 40 on the wire, emulation will be triggered on SIP/dinstar-00003783
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4206 __ast_read: DTMF end '4' has duration 40 but want minimum 80, emulating on SIP/dinstar-00003783
[Nov 25 11:36:17] DTMF[19103][C-0000532b]: channel.c:4281 __ast_read: DTMF end emulation of '4' queued on SIP/dinstar-00003783
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4247 __ast_read: DTMF begin '7' received on SIP/dinstar-00003782
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4258 __ast_read: DTMF begin passthrough '7' on SIP/dinstar-00003782
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4133 __ast_read: DTMF end '7' received on SIP/dinstar-00003782, duration 61 ms
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4184 __ast_read: DTMF end accepted with begin '7' on SIP/dinstar-00003782
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4199 __ast_read: DTMF end '7' detected to have actual duration 60 on the wire, emulation will be triggered on SIP/dinstar-00003782
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4206 __ast_read: DTMF end '7' has duration 60 but want minimum 80, emulating on SIP/dinstar-00003782
[Nov 25 11:36:23] DTMF[19096][C-0000532a]: channel.c:4281 __ast_read: DTMF end emulation of '7' queued on SIP/dinstar-00003782
[Nov 25 11:36:23]     -- SIP/dinstar-00003783 answered Local/09636464396@default-00003783;2
[Nov 25 11:36:23]     -- Local/09636464396@default-00003783;1 answered
[Nov 25 11:36:23]     -- Executing [138366@default:1] AGI("Local/09636464396@default-00003783;1", "agi-VDAD_local_optimize.agi,V1251136020000083925") in new stack
[Nov 25 11:36:23]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:36:23]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:23]     -- Channel SIP/dinstar-00003783 joined 'simple_bridge' basic-bridge <4786fe17-da03-4b68-9c4d-517c7463c0a9>
[Nov 25 11:36:23]     -- Channel Local/09636464396@default-00003783;2 joined 'simple_bridge' basic-bridge <4786fe17-da03-4b68-9c4d-517c7463c0a9>
[Nov 25 11:36:23]     -- <Local/09636464396@default-00003783;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:36:23]     -- Executing [138366@default:2] Wait("Local/09636464396@default-00003783;1", "2") in new stack
[Nov 25 11:36:23]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:23]     -- Channel SIP/dinstar-00003783 left 'simple_bridge' basic-bridge <4786fe17-da03-4b68-9c4d-517c7463c0a9>
[Nov 25 11:36:23]     -- Channel Local/09636464396@default-00003783;2 left 'simple_bridge' basic-bridge <4786fe17-da03-4b68-9c4d-517c7463c0a9>
[Nov 25 11:36:23]   == Spawn extension (default, 09636464396, 2) exited non-zero on 'Local/09636464396@default-00003783;2'
[Nov 25 11:36:23]     -- Executing [h@default:1] AGI("Local/09636464396@default-00003783;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----0-----SIP 200 OK)") in new stack
[Nov 25 11:36:23]     -- Executing [8366@default:1] AGI("SIP/dinstar-00003783", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:23]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:23]     -- <SIP/dinstar-00003783>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:23]     -- Executing [8366@default:2] AGI("SIP/dinstar-00003783", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251136020000083925") in new stack
[Nov 25 11:36:23]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:36:23]     -- <Local/09636464396@default-00003783;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:36:23]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09636464396@default-00003783;1'
[Nov 25 11:36:23] WARNING[19101][C-00005331]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:36:23]     -- Executing [h@default:1] AGI("Local/09636464396@default-00003783;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:36:23]     -- <Local/09636464396@default-00003783;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:36:24]     -- <SIP/dinstar-00003783> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:36:24]     -- <SIP/dinstar-00003783> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:36:24]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:25]     -- <SIP/dinstar-00003783>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:36:25]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-00003783", "") in new stack
[Nov 25 11:36:25]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-00003783", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:36:25]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:36:25]     -- <SIP/dinstar-00003783>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:36:25]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-00003783", "INVCOUNT=0") in new stack
[Nov 25 11:36:25]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-00003783", "IDFCBL") in new stack
[Nov 25 11:36:25]     -- <SIP/dinstar-00003783> Playing 'IDFCBL.slin' (language 'en')
[Nov 25 11:36:26] DTMF[19147][C-0000532b]: channel.c:4247 __ast_read: DTMF begin '2' received on SIP/dinstar-00003783
[Nov 25 11:36:26] DTMF[19147][C-0000532b]: channel.c:4251 __ast_read: DTMF begin ignored '2' on SIP/dinstar-00003783
[Nov 25 11:36:26] DTMF[19147][C-0000532b]: channel.c:4133 __ast_read: DTMF end '2' received on SIP/dinstar-00003783, duration 20 ms
[Nov 25 11:36:26] DTMF[19147][C-0000532b]: channel.c:4222 __ast_read: DTMF end passthrough '2' on SIP/dinstar-00003783
[Nov 25 11:36:26]     -- Invalid extension '2' in context 'IDFC-BL' on SIP/dinstar-00003783
[Nov 25 11:36:26]     -- Executing [i@IDFC-BL:1] Playback("SIP/dinstar-00003783", "IDFCPL-BandPass") in new stack
[Nov 25 11:36:26]     -- <SIP/dinstar-00003783> Playing 'IDFCPL-BandPass.slin' (language 'en')
[Nov 25 11:36:27]     -- SIP/dinstar-00003782 answered Local/09427949856@default-00003782;2
[Nov 25 11:36:27]     -- Local/09427949856@default-00003782;1 answered
[Nov 25 11:36:27]     -- Channel SIP/dinstar-00003782 joined 'simple_bridge' basic-bridge <53cebc99-c5f0-4f67-bb3b-dde3a307591d>
[Nov 25 11:36:27]     -- Executing [138366@default:1] AGI("Local/09427949856@default-00003782;1", "agi-VDAD_local_optimize.agi,V1251136020000071560") in new stack
[Nov 25 11:36:27]     -- Channel Local/09427949856@default-00003782;2 joined 'simple_bridge' basic-bridge <53cebc99-c5f0-4f67-bb3b-dde3a307591d>
[Nov 25 11:36:27]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Nov 25 11:36:27]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:27]     -- <Local/09427949856@default-00003782;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Nov 25 11:36:27]     -- Executing [138366@default:2] Wait("Local/09427949856@default-00003782;1", "2") in new stack
[Nov 25 11:36:27]   == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 11:36:27]     -- Channel SIP/dinstar-00003782 left 'simple_bridge' basic-bridge <53cebc99-c5f0-4f67-bb3b-dde3a307591d>
[Nov 25 11:36:27]     -- Executing [8366@default:1] AGI("SIP/dinstar-00003782", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 11:36:27]     -- Channel Local/09427949856@default-00003782;2 left 'simple_bridge' basic-bridge <53cebc99-c5f0-4f67-bb3b-dde3a307591d>
[Nov 25 11:36:27]   == Spawn extension (default, 09427949856, 2) exited non-zero on 'Local/09427949856@default-00003782;2'
[Nov 25 11:36:27]     -- Executing [h@default:1] AGI("Local/09427949856@default-00003782;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----24-----0-----SIP 200 OK)") in new stack
[Nov 25 11:36:27]     -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=IDFCBL))
[Nov 25 11:36:27]     -- <SIP/dinstar-00003782>AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 11:36:27]     -- Executing [8366@default:2] AGI("SIP/dinstar-00003782", "agi-VDAD_ALL_outbound.agi,SURVEYCAMP-----LB-----V1251136020000071560") in new stack
[Nov 25 11:36:27]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Nov 25 11:36:27]     -- <Local/09427949856@default-00003782;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----24-----0-----SIP 200 OK) completed, returning 0
[Nov 25 11:36:27]   == Spawn extension (default, 138366, 2) exited non-zero on 'Local/09427949856@default-00003782;1'
[Nov 25 11:36:27] WARNING[19095][C-00005332]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Nov 25 11:36:27]     -- Executing [h@default:1] AGI("Local/09427949856@default-00003782;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Nov 25 11:36:27]     -- <Local/09427949856@default-00003782;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Nov 25 11:36:28]     -- <SIP/dinstar-00003782> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Nov 25 11:36:28]     -- <SIP/dinstar-00003782> Playing 'quicksilence.ulaw' (escape_digits=1234567890*#) (sample_offset 0) (language 'en')
[Nov 25 11:36:28]   == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 11:36:29]     -- <SIP/dinstar-00003782>AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Nov 25 11:36:29]     -- Executing [s@IDFC-BL:1] Answer("SIP/dinstar-00003782", "") in new stack
[Nov 25 11:36:29]     -- Executing [s@IDFC-BL:2] AGI("SIP/dinstar-00003782", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----NO-----IDFC-BL-------------------------NO-----YES") in new stack
[Nov 25 11:36:29]     -- Launched AGI Script /usr/share/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
[Nov 25 11:36:29]     -- <SIP/dinstar-00003782>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
[Nov 25 11:36:29]     -- Executing [s@IDFC-BL:3] Set("SIP/dinstar-00003782", "INVCOUNT=0") in new stack
[Nov 25 11:36:29]     -- Executing [s@IDFC-BL:4] BackGround("SIP/dinstar-00003782", "IDFCBL") in new stack
[Nov 25 11:36:29]     -- <SIP/dinstar-00003782> Playing 'IDFCBL.slin' (language 'en')
[Nov 25 11:36:29]     -- SIP/dinstar-00003788 redirecting info has changed, passing it to Local/09328022615@default-00003788;2
[Nov 25 11:36:29]     -- SIP/dinstar-00003788 is busy
[Nov 25 11:36:29]   == Everyone is busy/congested at this time (1:1/0/0)
[Nov 25 11:36:29]     -- Executing [09328022615@default:3] Hangup("Local/09328022615@default-00003788;2", "") in new stack
[Nov 25 11:36:29]   == Spawn extension (default, 09328022615, 3) exited non-zero on 'Local/09328022615@default-00003788;2'
[Nov 25 11:36:29]     -- Executing [h@default:1] AGI("Local/09328022615@default-00003788;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----19-----BUSY---------------SIP 480 Temporarily not available)") in new stack
[Nov 25 11:36:29]     -- <Local/09328022615@default-00003788;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----19-----BUSY---------------SIP 480 Temporarily not available) completed, returning 0
[Nov 25 11:36:29]   == Manager 'sendcron' logged off from 127.0.0.1


Also pls let me know if there is something I am missing in settings too or something else that I have to correct.

Thanks
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby dinstar » Thu Nov 25, 2021 9:48 am

Pls assist me somebody.

Thanks
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: Changing Hopper script running time

Postby dinstar » Tue Nov 30, 2021 9:13 am

Hey! Can somebody help?

Thanks
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: How to increase calling speed?

Postby dinstar » Mon Dec 06, 2021 1:06 am

Hey @mflorell I request you and all other mods and experts to guide me regarding this issue.

I do understand that this is a free forum, and everybody helps as per his/her convenience, but this is the last major set-back that I am facing. I am willing to provide all details need to resolve it.

Also, this Vicidial system is for my personal use as startup hence low budget way, I am a one man army implementing this on my own, as of now 20 y.o., just finished college.

Thanks and Regards
Hoping to hear back from you soon.
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: How to increase calling speed?

Postby dinstar » Wed Dec 08, 2021 1:47 am

Posting GSM Events logs below. There you can see that vicidial is dialing every minute and calls ends very early, i.e 12sec avg call time, hence channels remains idle till next minute.

Link to Google Sheet of GSM Logs: https://docs.google.com/spreadsheets/d/ ... sp=sharing

Thanks
Vicibox v.9.0.2 200415-1712 iso installation
Asterisk 13.38.2-vici
OpenSuse Lead 15.2
Dinstar GSM Gateway
dinstar
 
Posts: 24
Joined: Wed Nov 10, 2021 3:18 pm

Re: How to increase calling speed?

Postby mflorell » Wed Dec 08, 2021 8:16 am

I haven't set up or used a GSM gateway in years, and we currently don't have any clients that use one, so I'm not really an expert on how to optimize VICIdial operations for one.
mflorell
Site Admin
 
Posts: 17913
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida


Return to Support

Who is online

Users browsing this forum: Majestic-12 [Bot] and 41 guests