Customer has hung up....

All installation and configuration problems and questions

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

Customer has hung up....

Postby michaelux » Sat Sep 26, 2009 12:26 pm

Hi....

I have a box with debian 5, vicidial 2.0.4 , asterisk 1.2.24.

Finally vicidial is almost working...

I can connect with eyebeam and login in the agent , then when I run the campaing with resume.. it starts to do the calls. I am testing with with my phone and with a friend..

When the other person pick up the phone it start LIVE CALL, I can listen and it is OK.. but after some seconds the webpage say " customer has hung up" but I am still listening and talking with him :s , I can clic Back and keel in the live call... but why can this happen?

I test with the old vicidial server and I when you are in live call you can see down in the page something like:

Show conference call channel information.

When I click there in the other server there are 2 channel,

1 sip channel
1 iax2 channel.

But in the new server only see 1 sip channel.

Our server has a iax2 tunel with another asterisk server and it connect to the carrier... the old server has the same configuration and it work ok.


When I test calling to my phone with the campaing I notice this:

The vicidial star LIVEL CALL but with the answer machine, and then my phone keep ringing.....

The campaing list only has my phone number many times... in some minutes I will put some logs..
michaelux
 
Posts: 39
Joined: Wed Aug 19, 2009 11:02 am

Postby williamconley » Sat Sep 26, 2009 8:48 pm

save yourself A LOT of time and headache. install vicibox or vicidialnow.

otherwise, you'll have to go deep into your /var/log/astguiclient logs and compare between your two machines to find out where the flaw is and then track it back to a setting or configuration issue (or an incompatible/slightly different dependency). issues like this can be resolved, but after the release of vicibox ... there's not a lot of point to it. seriously.
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
williamconley
 
Posts: 20258
Joined: Wed Oct 31, 2007 4:17 pm
Location: Davenport, FL (By Disney!)

Postby michaelux » Sat Sep 26, 2009 11:18 pm

Yes. I did, I installed vicidialnow but then the vicidial try to do the call and when the person pick up the phone.. the call finish :s I try to see what happen and ask but I could fix...

Now I am installing the same version and in Debian, like it was the old server...
michaelux
 
Posts: 39
Joined: Wed Aug 19, 2009 11:02 am

Postby williamconley » Sun Sep 27, 2009 4:48 pm

you need to find out what causes the hangup when the prospect picks up the phone. there's always a reason. and then you've have a working box that's up to date.
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
williamconley
 
Posts: 20258
Joined: Wed Oct 31, 2007 4:17 pm
Location: Davenport, FL (By Disney!)

Re: Customer has hung up....

Postby necival » Fri Jul 12, 2013 2:14 pm

Hi William,

I'm having a similar issue. Here's one box having the problem, but I'm experiencing it on a few other versions as well.

ViciBox Redux v.3.1.15 release
VERSION: 2.6-380a
BUILD: 121029-0109
Asterisk 1.4.39.2-vici

Code: Select all
[Jul 12 15:07:34]     -- Executing [17862792223@trunkinbound:1] AGI("SIP/20 IN-00000007", "agi-DID_route.agi") in new stack
[Jul 12 15:07:34]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Jul 12 15:07:35] ERROR[8743]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:07:35]     -- AGI Script agi-DID_route.agi completed, returning 0
[Jul 12 15:07:35]     -- Executing [99909*4***DID@default:1] Answer("SIP/20 IN-00000007", "") in new stack
[Jul 12 15:07:35]     -- Executing [99909*4***DID@default:2] AGI("SIP/20 IN-00000007", "agi-VDAD_ALL_inbound.agi") in new stack
[Jul 12 15:07:35]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 15:07:35]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:35]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:36]     -- Started music on hold, class 'default', on SIP/20 IN-00000007
[Jul 12 15:07:40]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 15:07:40] Found
[Jul 12 15:07:40]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 15:07:40]     -- Executing [050*198*250*114*78600051@default:1] Goto("Local/050*198*250*114*78600051@default-4d9e,2", "default|78600051|1") in new stack
[Jul 12 15:07:40]     -- Goto (default,78600051,1)
[Jul 12 15:07:40]     -- Executing [78600051@default:1] MeetMe("Local/050*198*250*114*78600051@default-4d9e,2", "8600051|Fq") in new stack
[Jul 12 15:07:40]     -- Executing [83047777777777@vicidial-auto:1] Answer("Local/050*198*250*114*78600051@default-4d9e,1", "") in new stack
[Jul 12 15:07:40]     -- Executing [83047777777777@vicidial-auto:2] Playback("Local/050*198*250*114*78600051@default-4d9e,1", "ding") in new stack
[Jul 12 15:07:40]     -- <Local/050*198*250*114*78600051@default-4d9e,1> Playing 'ding' (language 'en')
[Jul 12 15:07:40] WARNING[8757]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Jul 12 15:07:40]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 15:07:40] WARNING[8757]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Jul 12 15:07:40]     -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/050*198*250*114*78600051@default-4d9e,1", "") in new stack
[Jul 12 15:07:40]   == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/050*198*250*114*78600051@default-4d9e,1'
[Jul 12 15:07:40]     -- Executing [h@vicidial-auto:1] DeadAGI("Local/050*198*250*114*78600051@default-4d9e,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 15:07:40]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 15:07:40]   == Spawn extension (default, 78600051, 1) exited non-zero on 'Local/050*198*250*114*78600051@default-4d9e,2'
[Jul 12 15:07:40]     -- Executing [h@default:1] DeadAGI("Local/050*198*250*114*78600051@default-4d9e,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 15:07:40]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 15:07:41]     -- Stopped music on hold on SIP/20 IN-00000007
[Jul 12 15:07:41]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:41]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:42]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:42]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:42]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:42]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:07:42] ERROR[8743]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:07:42] ERROR[8743]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:07:42] ERROR[8743]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:07:42]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 15:07:42]     -- Executing [050*198*250*114*8600051@default:1] Goto("SIP/20 IN-00000007", "default|8600051|1") in new stack
[Jul 12 15:07:42]     -- Goto (default,8600051,1)
[Jul 12 15:07:42]     -- Executing [8600051@default:1] MeetMe("SIP/20 IN-00000007", "8600051|F") in new stack
[Jul 12 15:07:59]     -- Executing [17862792223@trunkinbound:1] AGI("SIP/20 IN-00000008", "agi-DID_route.agi") in new stack
[Jul 12 15:07:59]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Jul 12 15:07:59] ERROR[8778]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:07:59]     -- AGI Script agi-DID_route.agi completed, returning 0
[Jul 12 15:07:59]     -- Executing [99909*4***DID@default:1] Answer("SIP/20 IN-00000008", "") in new stack
[Jul 12 15:07:59]     -- Executing [99909*4***DID@default:2] AGI("SIP/20 IN-00000008", "agi-VDAD_ALL_inbound.agi") in new stack
[Jul 12 15:07:59]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 15:08:00]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:00]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:01]     -- Started music on hold, class 'default', on SIP/20 IN-00000008
[Jul 12 15:08:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 15:08:01] Found
[Jul 12 15:08:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 15:08:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 15:08:01] Found
[Jul 12 15:08:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 15:08:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 15:08:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 15:08:03]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 15:08:03] Found
[Jul 12 15:08:03]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 15:08:03]     -- Executing [050*198*250*114*78600051@default:1] Goto("Local/050*198*250*114*78600051@default-b242,2", "default|78600051|1") in new stack
[Jul 12 15:08:03]     -- Goto (default,78600051,1)
[Jul 12 15:08:03]     -- Executing [78600051@default:1] MeetMe("Local/050*198*250*114*78600051@default-b242,2", "8600051|Fq") in new stack
[Jul 12 15:08:03]     -- Executing [83047777777777@vicidial-auto:1] Answer("Local/050*198*250*114*78600051@default-b242,1", "") in new stack
[Jul 12 15:08:03]     -- Executing [83047777777777@vicidial-auto:2] Playback("Local/050*198*250*114*78600051@default-b242,1", "ding") in new stack
[Jul 12 15:08:03]     -- <Local/050*198*250*114*78600051@default-b242,1> Playing 'ding' (language 'en')
[Jul 12 15:08:03] WARNING[8812]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Jul 12 15:08:03] WARNING[8812]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Jul 12 15:08:03]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 15:08:03]     -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/050*198*250*114*78600051@default-b242,1", "") in new stack
[Jul 12 15:08:03]   == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/050*198*250*114*78600051@default-b242,1'
[Jul 12 15:08:03]     -- Executing [h@vicidial-auto:1] DeadAGI("Local/050*198*250*114*78600051@default-b242,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 15:08:03]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 15:08:03]   == Spawn extension (default, 78600051, 1) exited non-zero on 'Local/050*198*250*114*78600051@default-b242,2'
[Jul 12 15:08:03]     -- Executing [h@default:1] DeadAGI("Local/050*198*250*114*78600051@default-b242,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 15:08:03]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 15:08:04]     -- Stopped music on hold on SIP/20 IN-00000008
[Jul 12 15:08:04]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:04]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:04]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:04]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:04]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:04]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 15:08:04] ERROR[8778]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:08:04] ERROR[8778]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:08:04] ERROR[8778]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 12 15:08:04]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 15:08:04]     -- Executing [050*198*250*114*8600051@default:1] Goto("SIP/20 IN-00000008", "default|8600051|1") in new stack
[Jul 12 15:08:04]     -- Goto (default,8600051,1)
[Jul 12 15:08:04]     -- Executing [8600051@default:1] MeetMe("SIP/20 IN-00000008", "8600051|F") in new stack
[Jul 12 15:08:06]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 15:08:06] Found
[Jul 12 15:08:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 15:08:06]   == Manager 'sendcron' logged off from 127.0.0.1


There are two inbound calls in that log, the first one was "BLIND TRANSFERRED" and dispo'ed... but the system never transferred or hung-up the first caller. Second call is connected to the agent and now the agent has two callers on the phone, with no way to hang up the "transferred" call.

At that point the system posts a blue screen that says
Customer has hung up: SIP/20 IN-0000000c
Last edited by necival on Fri Jul 12, 2013 3:27 pm, edited 1 time in total.
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm

Re: Customer has hung up....

Postby necival » Fri Jul 12, 2013 2:19 pm

By the way, "HANGUP CUSTOMER" works unless you try to use the transfer once you try transfer, hangup no longer works. "PARK CALL" also does nothing at all.

All of these functions work fine on outbound calls...

I installed a new machine lastnight and it has the exact same issue:

ViciBox Redux v.3.1.15 release
VERSION: 2.8-407a
BUILD: 130709-1350
Asterisk 1.4.39.2-vici

Thanks!

Updated info:
"DIAL WITH CUSTOMER" works! But not Blind Xfer or Park+Dial Xfer or Xfer to VM
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm

Re: Customer has hung up....

Postby williamconley » Fri Jul 12, 2013 5:23 pm

sounds like you have an upgraded system. when you upgraded, did you allow it to install the sample .conf files? If not ... run install.pl again and this time say YES to that question.
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
williamconley
 
Posts: 20258
Joined: Wed Oct 31, 2007 4:17 pm
Location: Davenport, FL (By Disney!)

Re: Customer has hung up....

Postby necival » Fri Jul 12, 2013 6:14 pm

Thank you for your reply William, I ran:

Dialer:~ # perl /usr/src/astguiclient/trunk/install.pl

And here's the results (ip address replaced, obv):

Code: Select all
  defined conf file:        /etc/astguiclient.conf
  defined home path:        /usr/share/astguiclient
  defined logs path:        /var/log/astguiclient
  defined agi-bin path:     /var/lib/asterisk/agi-bin
  defined webroot path:     /srv/www/htdocs
  defined sounds path:      /var/lib/asterisk/sounds
  defined monitor path:     /var/spool/asterisk/monitor
  defined DONEmonitor path: /var/spool/asterisk/monitorDONE
  defined server_ip:        ---IPADDRESS---
  defined DB_server:        127.0.0.1
  defined DB_database:      asterisk
  defined DB_user:          cron
  defined DB_pass:          1234
  defined DB_custom_user:   custom
  defined DB_custom_pass:   custom1234
  defined DB_port:          3306
  defined active_keepalives:     12345689
  defined asterisk_version:      1.4
  defined copying conf files:    y
  defined copying weblang files: n
  defined FTP_host:         ---IPADDRESS---
  defined FTP_user:         cron
  defined FTP_pass:         1234
  defined FTP_port:         21
  defined FTP_dir:          RECORDINGS
  defined HTTP_path:        ---IPADDRESS---
  defined REPORT_host:      ---IPADDRESS---
  defined REPORT_user:      cron
  defined REPORT_pass:      1234
  defined REPORT_port:      21
  defined REPORT_dir:       REPORTS
  defined fastagi_log_min_servers:       3
  defined fastagi_log_max_servers:       16
  defined fastagi_log_min_spare_servers: 2
  defined fastagi_log_max_spare_servers: 8
  defined fastagi_log_max_requests:      1000
  defined fastagi_log_checkfordead:      30
  defined fastagi_log_checkforwait:      60

Are these settings correct?(y/n): [y]
Writing to configuration file: /etc/astguiclient.conf

STARTING ASTGUICLIENT INSTALLATION PHASE...
Creating /usr/share/astguiclient/LEADS_IN directories...
Creating /var/spool/asterisk/monitor directories...
Creating /var/log/astguiclient/archive directory for backups...
Copying bin scripts to /usr/share/astguiclient ...
cp: cannot stat `./bin/*': No such file or directory
Copying libs to /usr/share/astguiclient ...
cp: cannot stat `./libs/*': No such file or directory
cp: cannot stat `./extras/Asterisk.pm': No such file or directory
cp: cannot stat `./extras/Asterisk/*': No such file or directory
setting cron scripts to executable...
Copying extras files to /usr/share/astguiclient ...
cp: cannot stat `./extras/MySQL_AST_CREATE_tables.sql': No such file or directory
Copying agi-bin scripts to /var/lib/asterisk/agi-bin ...
cp: cannot stat `./agi/*': No such file or directory
setting agi-bin scripts to executable...
Copying sounds to /var/lib/asterisk/sounds...
cp: cannot stat `./sounds/*': No such file or directory
Creating sound tts directories...
Copying ip_relay scripts to /usr/share/astguiclient...
cp: cannot stat `./extras/ip_relay': No such file or directory
Setting ip_relay scripts to executable...
ln: creating symbolic link `/usr/share/astguiclient/ip_relay/ip_relay': File exists
ln: creating symbolic link `/usr/bin/ip_relay': File exists
ln: creating symbolic link `/usr/local/bin/ip_relay': File exists
Starting ip_relay port forwarding for IAX on 40569 and 41569
Creating /srv/www/htdocs web directories...
Copying web files...
cp: cannot stat `./www/*': No such file or directory
setting web scripts to executable...
Copying sample conf files to /etc/asterisk/...
cp: cannot stat `./docs/conf_examples/extensions.conf.sample-1.4': No such file or directory
cp: cannot stat `./docs/conf_examples/iax.conf.sample-1.4': No such file or directory
cp: cannot stat `./docs/conf_examples/sip.conf.sample-1.4': No such file or directory
cp: cannot stat `./docs/conf_examples/meetme.conf.sample': No such file or directory
cp: cannot stat `./docs/conf_examples/manager.conf.sample': No such file or directory
cp: cannot stat `./docs/conf_examples/musiconhold.conf.sample': No such file or directory
cp: cannot stat `./docs/conf_examples/voicemail.conf.sample': No such file or directory
cp: cannot stat `./docs/conf_examples/logger.conf.sample': No such file or directory
cp: cannot stat `./docs/conf_examples/dnsmgr.conf.sample': No such file or directory
cp: cannot stat `./docs/conf_examples/features.conf.sample': No such file or directory
Creating auto-generated placeholder conf files in /etc/asterisk/...
Updating version information in the database...
svn: '.' is not a working copy

|UPDATE servers SET svn_revision='0',svn_info='/root

' where server_ip='---IPADDRESS---';|

|UPDATE system_settings SET svn_revision='0';|
Version information updated: 0|---IPADDRESS---

ASTGUICLIENT VICIDIAL INSTALLATION FINISHED!     ENJOY!

     - process runtime      (30 sec) (0.5 minutes)

Last edited by necival on Fri Jul 12, 2013 6:20 pm, edited 1 time in total.
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm

Re: Customer has hung up....

Postby necival » Fri Jul 12, 2013 6:18 pm

Btw, here are my inbound trunk settings:

Code: Select all
[IN]
disallow=all
allow=g729,ulaw
host=---IPADDRESS---
context=trunkinbound
dtmf=rfc2833
type=peer
qualify=no
nat=no
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm

Re: Customer has hung up....

Postby williamconley » Fri Jul 12, 2013 8:42 pm

in that case it's time to scrub the astguiclient logs to see where the path of this call's logic "went astray".

/var/log/astguiclient

delete all of them. then make your call. then copy them somewhere so no more entries are made. then search through them and see what happened.

if you can, try the same thing on a test box that succeeds to see the difference.

if you don't have a test box, install in a vmware instance with vicibox 4.0.3 and set it up for a single agent on a single call.
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
williamconley
 
Posts: 20258
Joined: Wed Oct 31, 2007 4:17 pm
Location: Davenport, FL (By Disney!)

Re: Customer has hung up....

Postby necival » Mon Jul 15, 2013 10:01 am

Here are the logs that seemed relavant to the issue:

Code: Select all
[Jul 12 14:47:48] VERBOSE[6415] logger.c: [Jul 12 14:47:48]     -- Executing [17862792223@trunkinbound:1] AGI("SIP/20 IN-00000003", "agi-DID_route.agi") in new stack
[Jul 12 14:47:48] VERBOSE[6415] logger.c: [Jul 12 14:47:48]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Jul 12 14:47:48] ERROR[6415] utils.c: write() returned error: Broken pipe
[Jul 12 14:47:48] VERBOSE[6415] logger.c: [Jul 12 14:47:48]     -- AGI Script agi-DID_route.agi completed, returning 0
[Jul 12 14:47:48] VERBOSE[6415] logger.c: [Jul 12 14:47:48]     -- Executing [99909*4***DID@default:1] Answer("SIP/20 IN-00000003", "") in new stack
[Jul 12 14:47:48] VERBOSE[6415] logger.c: [Jul 12 14:47:48]     -- Executing [99909*4***DID@default:2] AGI("SIP/20 IN-00000003", "agi-VDAD_ALL_inbound.agi") in new stack
[Jul 12 14:47:48] VERBOSE[6415] logger.c: [Jul 12 14:47:48]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 14:47:49] VERBOSE[6415] logger.c: [Jul 12 14:47:49]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:47:49] VERBOSE[6415] logger.c: [Jul 12 14:47:49]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:47:50] VERBOSE[6415] logger.c: [Jul 12 14:47:50]     -- Started music on hold, class 'default', on SIP/20 IN-00000003
[Jul 12 14:48:02] VERBOSE[6448] logger.c: [Jul 12 14:48:02]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:02] VERBOSE[6448] logger.c: [Jul 12 14:48:02] Found
[Jul 12 14:48:02] VERBOSE[6448] logger.c: [Jul 12 14:48:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:02] VERBOSE[6450] logger.c: [Jul 12 14:48:02]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:02] VERBOSE[6450] logger.c: [Jul 12 14:48:02] Found
[Jul 12 14:48:02] VERBOSE[6450] logger.c: [Jul 12 14:48:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:02] VERBOSE[6448] logger.c: [Jul 12 14:48:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:03] VERBOSE[6450] logger.c: [Jul 12 14:48:03]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:07] VERBOSE[6461] logger.c: [Jul 12 14:48:07]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:07] VERBOSE[6461] logger.c: [Jul 12 14:48:07] Found
[Jul 12 14:48:07] VERBOSE[6461] logger.c: [Jul 12 14:48:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:07] VERBOSE[6461] logger.c: [Jul 12 14:48:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:12] VERBOSE[6415] logger.c: [Jul 12 14:48:12]     -- Stopped music on hold on SIP/20 IN-00000003
[Jul 12 14:48:12] VERBOSE[6415] logger.c: [Jul 12 14:48:12]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:12] VERBOSE[6415] logger.c: [Jul 12 14:48:12]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:13] VERBOSE[6415] logger.c: [Jul 12 14:48:13]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:13] VERBOSE[6415] logger.c: [Jul 12 14:48:13]     -- Playing 'generic_hold' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:14] VERBOSE[6415] logger.c: [Jul 12 14:48:14]     -- Started music on hold, class 'default', on SIP/20 IN-00000003
[Jul 12 14:48:18] VERBOSE[6476] logger.c: [Jul 12 14:48:18]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:18] VERBOSE[6476] logger.c: [Jul 12 14:48:18] Found
[Jul 12 14:48:18] VERBOSE[6476] logger.c: [Jul 12 14:48:18]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:18] VERBOSE[6477] logger.c: [Jul 12 14:48:18]     -- Executing [050*198*250*114*78600051@default:1] Goto("Local/050*198*250*114*78600051@default-1d86,2", "default|78600051|1") in new stack
[Jul 12 14:48:18] VERBOSE[6477] logger.c: [Jul 12 14:48:18]     -- Goto (default,78600051,1)
[Jul 12 14:48:18] VERBOSE[6477] logger.c: [Jul 12 14:48:18]     -- Executing [78600051@default:1] MeetMe("Local/050*198*250*114*78600051@default-1d86,2", "8600051|Fq") in new stack
[Jul 12 14:48:18] VERBOSE[6476] logger.c: [Jul 12 14:48:18]        > Channel Local/050*198*250*114*78600051@default-1d86,1 was answered.
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]     -- Executing [83047777777777@vicidial-auto:1] Answer("Local/050*198*250*114*78600051@default-1d86,1", "") in new stack
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]     -- Executing [83047777777777@vicidial-auto:2] Playback("Local/050*198*250*114*78600051@default-1d86,1", "ding") in new stack
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]     -- <Local/050*198*250*114*78600051@default-1d86,1> Playing 'ding' (language 'en')
[Jul 12 14:48:18] WARNING[6478] file.c: Unexpected control subclass '-1'
[Jul 12 14:48:18] WARNING[6478] file.c: Unexpected control subclass '-1'
[Jul 12 14:48:18] VERBOSE[6476] logger.c: [Jul 12 14:48:18]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]     -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/050*198*250*114*78600051@default-1d86,1", "") in new stack
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]   == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/050*198*250*114*78600051@default-1d86,1'
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]     -- Executing [h@vicidial-auto:1] DeadAGI("Local/050*198*250*114*78600051@default-1d86,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:48:18] VERBOSE[6478] logger.c: [Jul 12 14:48:18]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:48:18] VERBOSE[6477] logger.c: [Jul 12 14:48:18]   == Spawn extension (default, 78600051, 1) exited non-zero on 'Local/050*198*250*114*78600051@default-1d86,2'
[Jul 12 14:48:18] VERBOSE[6477] logger.c: [Jul 12 14:48:18]     -- Executing [h@default:1] DeadAGI("Local/050*198*250*114*78600051@default-1d86,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 14:48:18] VERBOSE[6477] logger.c: [Jul 12 14:48:18]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Stopped music on hold on SIP/20 IN-00000003
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:19] ERROR[6415] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:19] ERROR[6415] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:19] ERROR[6415] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Executing [050*198*250*114*8600051@default:1] Goto("SIP/20 IN-00000003", "default|8600051|1") in new stack
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Goto (default,8600051,1)
[Jul 12 14:48:19] VERBOSE[6415] logger.c: [Jul 12 14:48:19]     -- Executing [8600051@default:1] MeetMe("SIP/20 IN-00000003", "8600051|F") in new stack
[Jul 12 14:48:26] VERBOSE[6497] logger.c: [Jul 12 14:48:26]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:26] VERBOSE[6497] logger.c: [Jul 12 14:48:26] Found
[Jul 12 14:48:26] VERBOSE[6497] logger.c: [Jul 12 14:48:26]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:26] VERBOSE[6415] logger.c: [Jul 12 14:48:26]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/20 IN-00000003'
[Jul 12 14:48:26] VERBOSE[6415] logger.c: [Jul 12 14:48:26]     -- Executing [h@default:1] DeadAGI("SIP/20 IN-00000003", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 14:48:26] VERBOSE[6497] logger.c: [Jul 12 14:48:26]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:26] VERBOSE[6415] logger.c: [Jul 12 14:48:26]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 14:48:26] VERBOSE[6498] logger.c: [Jul 12 14:48:26]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:26] VERBOSE[6498] logger.c: [Jul 12 14:48:26] Found
[Jul 12 14:48:26] VERBOSE[6498] logger.c: [Jul 12 14:48:26]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:26] VERBOSE[6498] logger.c: [Jul 12 14:48:26]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Executing [17862792223@trunkinbound:1] AGI("SIP/20 IN-00000004", "agi-DID_route.agi") in new stack
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Jul 12 14:48:48] ERROR[6524] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- AGI Script agi-DID_route.agi completed, returning 0
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Executing [99909*4***DID@default:1] Answer("SIP/20 IN-00000004", "") in new stack
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Executing [99909*4***DID@default:2] AGI("SIP/20 IN-00000004", "agi-VDAD_ALL_inbound.agi") in new stack
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:48] VERBOSE[6524] logger.c: [Jul 12 14:48:48]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:49] VERBOSE[6531] logger.c: [Jul 12 14:48:49]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:48:49] VERBOSE[6531] logger.c: [Jul 12 14:48:49] Found
[Jul 12 14:48:49] VERBOSE[6531] logger.c: [Jul 12 14:48:49]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:48:49] VERBOSE[6532] logger.c: [Jul 12 14:48:49]     -- Executing [050*198*250*114*78600051@default:1] Goto("Local/050*198*250*114*78600051@default-8a24,2", "default|78600051|1") in new stack
[Jul 12 14:48:49] VERBOSE[6532] logger.c: [Jul 12 14:48:49]     -- Goto (default,78600051,1)
[Jul 12 14:48:49] VERBOSE[6532] logger.c: [Jul 12 14:48:49]     -- Executing [78600051@default:1] MeetMe("Local/050*198*250*114*78600051@default-8a24,2", "8600051|Fq") in new stack
[Jul 12 14:48:49] VERBOSE[6531] logger.c: [Jul 12 14:48:49]        > Channel Local/050*198*250*114*78600051@default-8a24,1 was answered.
[Jul 12 14:48:49] VERBOSE[6533] logger.c: [Jul 12 14:48:49]     -- Executing [83047777777777@vicidial-auto:1] Answer("Local/050*198*250*114*78600051@default-8a24,1", "") in new stack
[Jul 12 14:48:49] VERBOSE[6531] logger.c: [Jul 12 14:48:49]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:48:49] VERBOSE[6533] logger.c: [Jul 12 14:48:49]     -- Executing [83047777777777@vicidial-auto:2] Playback("Local/050*198*250*114*78600051@default-8a24,1", "ding") in new stack
[Jul 12 14:48:49] VERBOSE[6533] logger.c: [Jul 12 14:48:49]     -- <Local/050*198*250*114*78600051@default-8a24,1> Playing 'ding' (language 'en')
[Jul 12 14:48:49] WARNING[6533] file.c: Unexpected control subclass '-1'
[Jul 12 14:48:49] WARNING[6533] file.c: Unexpected control subclass '-1'
[Jul 12 14:48:50] VERBOSE[6533] logger.c: [Jul 12 14:48:50]     -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/050*198*250*114*78600051@default-8a24,1", "") in new stack
[Jul 12 14:48:50] VERBOSE[6533] logger.c: [Jul 12 14:48:50]   == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/050*198*250*114*78600051@default-8a24,1'
[Jul 12 14:48:50] VERBOSE[6533] logger.c: [Jul 12 14:48:50]     -- Executing [h@vicidial-auto:1] DeadAGI("Local/050*198*250*114*78600051@default-8a24,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:48:50] VERBOSE[6533] logger.c: [Jul 12 14:48:50]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:48:50] VERBOSE[6532] logger.c: [Jul 12 14:48:50]   == Spawn extension (default, 78600051, 1) exited non-zero on 'Local/050*198*250*114*78600051@default-8a24,2'
[Jul 12 14:48:50] VERBOSE[6532] logger.c: [Jul 12 14:48:50]     -- Executing [h@default:1] DeadAGI("Local/050*198*250*114*78600051@default-8a24,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 14:48:50] VERBOSE[6532] logger.c: [Jul 12 14:48:50]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 14:48:50] VERBOSE[6524] logger.c: [Jul 12 14:48:50]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:50] VERBOSE[6524] logger.c: [Jul 12 14:48:50]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:51] ERROR[6524] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:51] ERROR[6524] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:51] ERROR[6524] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Executing [050*198*250*114*8600051@default:1] Goto("SIP/20 IN-00000004", "default|8600051|1") in new stack
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Goto (default,8600051,1)
[Jul 12 14:48:51] VERBOSE[6524] logger.c: [Jul 12 14:48:51]     -- Executing [8600051@default:1] MeetMe("SIP/20 IN-00000004", "8600051|F") in new stack
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Executing [17862792223@trunkinbound:1] AGI("SIP/20 IN-00000005", "agi-DID_route.agi") in new stack
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Jul 12 14:48:55] ERROR[6539] utils.c: write() returned error: Broken pipe
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- AGI Script agi-DID_route.agi completed, returning 0
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Executing [99909*4***DID@default:1] Answer("SIP/20 IN-00000005", "") in new stack
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Executing [99909*4***DID@default:2] AGI("SIP/20 IN-00000005", "agi-VDAD_ALL_inbound.agi") in new stack
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:55] VERBOSE[6539] logger.c: [Jul 12 14:48:55]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:48:56] VERBOSE[6539] logger.c: [Jul 12 14:48:56]     -- Started music on hold, class 'default', on SIP/20 IN-00000005
[Jul 12 14:49:01] VERBOSE[6563] logger.c: [Jul 12 14:49:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:49:01] VERBOSE[6563] logger.c: [Jul 12 14:49:01] Found
[Jul 12 14:49:01] VERBOSE[6563] logger.c: [Jul 12 14:49:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:49:01] VERBOSE[6567] logger.c: [Jul 12 14:49:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:49:01] VERBOSE[6567] logger.c: [Jul 12 14:49:01] Found
[Jul 12 14:49:01] VERBOSE[6567] logger.c: [Jul 12 14:49:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:49:01] VERBOSE[6567] logger.c: [Jul 12 14:49:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:49:02] VERBOSE[6563] logger.c: [Jul 12 14:49:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:49:06] VERBOSE[6573] logger.c: [Jul 12 14:49:06]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:49:06] VERBOSE[6573] logger.c: [Jul 12 14:49:06] Found
[Jul 12 14:49:06] VERBOSE[6573] logger.c: [Jul 12 14:49:06]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:49:06] VERBOSE[6573] logger.c: [Jul 12 14:49:06]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:49:07] VERBOSE[6583] logger.c: [Jul 12 14:49:07]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:49:07] VERBOSE[6583] logger.c: [Jul 12 14:49:07] Found
[Jul 12 14:49:07] VERBOSE[6583] logger.c: [Jul 12 14:49:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]     -- Executing [90009**CXFER*235634**3057987510*1000*8667664000*16*@default:1] Answer("Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392", "") in new stack
[Jul 12 14:49:07] VERBOSE[6583] logger.c: [Jul 12 14:49:07]        > Channel Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392e,1 was answered.
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]     -- Executing [90009**CXFER*235634**3057987510*1000*8667664000*16*@default:2] Dial("Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392", "IAX2/ASTloop:omJp2H1ch7CEPiLlnrcn@127.0.0.1:40569/990009**CXFER*235634**3057987510*1000*8667664000*16*||to") in new stack
[Jul 12 14:49:07] VERBOSE[6585] logger.c: [Jul 12 14:49:07]     -- Executing [8600051@default:1] MeetMe("Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392", "8600051|F") in new stack
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]     -- Called ASTloop:omJp2H1ch7CEPiLlnrcn@127.0.0.1:40569/990009**CXFER*235634**3057987510*1000*8667664000*16*
[Jul 12 14:49:07] VERBOSE[6583] logger.c: [Jul 12 14:49:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:49:07] VERBOSE[26425] logger.c: [Jul 12 14:49:07]     -- Accepting AUTHENTICATED call from 127.0.0.1:
       > requested format = gsm,
       > requested prefs = (gsm|ulaw),
       > actual format = ulaw,
       > host prefs = (ulaw),
       > priority = mine
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Executing [990009**CXFER*235634**3057987510*1000*8667664000*16*@default:1] Answer("IAX2/ASTloop-1650", "") in new stack
[Jul 12 14:49:07] VERBOSE[26424] logger.c: [Jul 12 14:49:07]     -- Call accepted by 127.0.0.1 (format ulaw)
[Jul 12 14:49:07] VERBOSE[26424] logger.c: [Jul 12 14:49:07]     -- Format for call is ulaw
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Executing [990009**CXFER*235634**3057987510*1000*8667664000*16*@default:2] AGI("IAX2/ASTloop-1650", "agi-VDAD_ALL_inbound.agi|CLOSER-----LB-----CL_TESTCAMP-----7275551212-----Closer-----park----------999-----1") in new stack
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]     -- IAX2/127.0.0.1:40569-13788 answered Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392e,2
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]     -- Executing [h@default:1] DeadAGI("Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----0-----0") in new stack
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Executing [990009**CXFER*235634**3057987510*1000*8667664000*16*@default:3] Hangup("IAX2/ASTloop-1650", "") in new stack
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]   == Spawn extension (default, 990009**CXFER*235634**3057987510*1000*8667664000*16*, 3) exited non-zero on 'IAX2/ASTloop-1650'
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Executing [h@default:1] DeadAGI("IAX2/ASTloop-1650", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----0-----0 completed, returning 0
[Jul 12 14:49:07] VERBOSE[6584] logger.c: [Jul 12 14:49:07]   == Spawn extension (default, 90009**CXFER*235634**3057987510*1000*8667664000*16*, 2) exited non-zero on 'Local/90009**CXFER*235634**3057987510*1000*8667664000*16*@default-392e,2'
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:49:07] VERBOSE[6586] logger.c: [Jul 12 14:49:07]     -- Hungup 'IAX2/ASTloop-1650'
[Jul 12 14:49:07] VERBOSE[6585] logger.c: [Jul 12 14:49:07]   == Spawn extension (default, 8600051, 1) exited non-zero on 'IAX2/127.0.0.1:40569-13788'
[Jul 12 14:49:07] VERBOSE[6585] logger.c: [Jul 12 14:49:07]     -- Executing [h@default:1] DeadAGI("IAX2/127.0.0.1:40569-13788", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:49:07] VERBOSE[6585] logger.c: [Jul 12 14:49:07]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:49:07] VERBOSE[6585] logger.c: [Jul 12 14:49:07]     -- Hungup 'IAX2/127.0.0.1:40569-13788'
[Jul 12 14:49:18] VERBOSE[6539] logger.c: [Jul 12 14:49:18]     -- Stopped music on hold on SIP/20 IN-00000005
[Jul 12 14:49:18] VERBOSE[6539] logger.c: [Jul 12 14:49:18]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:18] VERBOSE[6539] logger.c: [Jul 12 14:49:18]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:19] VERBOSE[6539] logger.c: [Jul 12 14:49:19]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:19] VERBOSE[6539] logger.c: [Jul 12 14:49:19]     -- Playing 'generic_hold' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:20] VERBOSE[6539] logger.c: [Jul 12 14:49:20]     -- Started music on hold, class 'default', on SIP/20 IN-00000005
[Jul 12 14:49:23] VERBOSE[6608] logger.c: [Jul 12 14:49:23]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:49:23] VERBOSE[6608] logger.c: [Jul 12 14:49:23] Found
[Jul 12 14:49:23] VERBOSE[6608] logger.c: [Jul 12 14:49:23]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:49:23] VERBOSE[6608] logger.c: [Jul 12 14:49:23]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:49:27] VERBOSE[6616] logger.c: [Jul 12 14:49:27]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:49:27] VERBOSE[6616] logger.c: [Jul 12 14:49:27] Found
[Jul 12 14:49:27] VERBOSE[6616] logger.c: [Jul 12 14:49:27]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]     -- Executing [90009**CXFER*235634**3057987510*1000*8667664000*36*@default:1] Answer("Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d", "") in new stack
[Jul 12 14:49:27] VERBOSE[6616] logger.c: [Jul 12 14:49:27]        > Channel Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d7,1 was answered.
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]     -- Executing [90009**CXFER*235634**3057987510*1000*8667664000*36*@default:2] Dial("Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d", "IAX2/ASTloop:omJp2H1ch7CEPiLlnrcn@127.0.0.1:40569/990009**CXFER*235634**3057987510*1000*8667664000*36*||to") in new stack
[Jul 12 14:49:27] VERBOSE[6618] logger.c: [Jul 12 14:49:27]     -- Executing [8600051@default:1] MeetMe("Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d", "8600051|F") in new stack
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]     -- Called ASTloop:omJp2H1ch7CEPiLlnrcn@127.0.0.1:40569/990009**CXFER*235634**3057987510*1000*8667664000*36*
[Jul 12 14:49:27] VERBOSE[26409] logger.c: [Jul 12 14:49:27]     -- Accepting AUTHENTICATED call from 127.0.0.1:
       > requested format = gsm,
       > requested prefs = (gsm|ulaw),
       > actual format = ulaw,
       > host prefs = (ulaw),
       > priority = mine
[Jul 12 14:49:27] VERBOSE[6616] logger.c: [Jul 12 14:49:27]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Executing [990009**CXFER*235634**3057987510*1000*8667664000*36*@default:1] Answer("IAX2/ASTloop-7653", "") in new stack
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Executing [990009**CXFER*235634**3057987510*1000*8667664000*36*@default:2] AGI("IAX2/ASTloop-7653", "agi-VDAD_ALL_inbound.agi|CLOSER-----LB-----CL_TESTCAMP-----7275551212-----Closer-----park----------999-----1") in new stack
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 12 14:49:27] VERBOSE[26447] logger.c: [Jul 12 14:49:27]     -- Call accepted by 127.0.0.1 (format ulaw)
[Jul 12 14:49:27] VERBOSE[26447] logger.c: [Jul 12 14:49:27]     -- Format for call is ulaw
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]     -- IAX2/127.0.0.1:40569-192 answered Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d7,2
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]     -- Executing [h@default:1] DeadAGI("Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----0-----0") in new stack
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----0-----0 completed, returning 0
[Jul 12 14:49:27] VERBOSE[6617] logger.c: [Jul 12 14:49:27]   == Spawn extension (default, 90009**CXFER*235634**3057987510*1000*8667664000*36*, 2) exited non-zero on 'Local/90009**CXFER*235634**3057987510*1000*8667664000*36*@default-51d7,2'
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Executing [990009**CXFER*235634**3057987510*1000*8667664000*36*@default:3] Hangup("IAX2/ASTloop-7653", "") in new stack
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]   == Spawn extension (default, 990009**CXFER*235634**3057987510*1000*8667664000*36*, 3) exited non-zero on 'IAX2/ASTloop-7653'
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Executing [h@default:1] DeadAGI("IAX2/ASTloop-7653", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:49:27] VERBOSE[6619] logger.c: [Jul 12 14:49:27]     -- Hungup 'IAX2/ASTloop-7653'
[Jul 12 14:49:27] VERBOSE[6618] logger.c: [Jul 12 14:49:27]   == Spawn extension (default, 8600051, 1) exited non-zero on 'IAX2/127.0.0.1:40569-192'
[Jul 12 14:49:27] VERBOSE[6618] logger.c: [Jul 12 14:49:27]     -- Executing [h@default:1] DeadAGI("IAX2/127.0.0.1:40569-192", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:49:27] VERBOSE[6618] logger.c: [Jul 12 14:49:27]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:49:27] VERBOSE[6618] logger.c: [Jul 12 14:49:27]     -- Hungup 'IAX2/127.0.0.1:40569-192'
[Jul 12 14:50:00] VERBOSE[6664] logger.c: [Jul 12 14:50:00]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:50:00] VERBOSE[6664] logger.c: [Jul 12 14:50:00] Found
[Jul 12 14:50:00] VERBOSE[6664] logger.c: [Jul 12 14:50:00]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:50:00] VERBOSE[6664] logger.c: [Jul 12 14:50:00]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:50:01] VERBOSE[6681] logger.c: [Jul 12 14:50:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:50:01] VERBOSE[6681] logger.c: [Jul 12 14:50:01] Found
[Jul 12 14:50:01] VERBOSE[6681] logger.c: [Jul 12 14:50:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:50:01] VERBOSE[6682] logger.c: [Jul 12 14:50:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:50:01] VERBOSE[6682] logger.c: [Jul 12 14:50:01] Found
[Jul 12 14:50:01] VERBOSE[6682] logger.c: [Jul 12 14:50:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:50:02] VERBOSE[6682] logger.c: [Jul 12 14:50:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:50:03] VERBOSE[6681] logger.c: [Jul 12 14:50:03]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:50:03] VERBOSE[26246] logger.c: [Jul 12 14:50:03]   == Refreshing DNS lookups.
[Jul 12 14:50:07] VERBOSE[6689] logger.c: [Jul 12 14:50:07]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:50:07] VERBOSE[6689] logger.c: [Jul 12 14:50:07] Found
[Jul 12 14:50:07] VERBOSE[6689] logger.c: [Jul 12 14:50:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:50:07] VERBOSE[6689] logger.c: [Jul 12 14:50:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:50:14] VERBOSE[6701] logger.c: [Jul 12 14:50:14]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:50:14] VERBOSE[6701] logger.c: [Jul 12 14:50:14] Found
[Jul 12 14:50:14] VERBOSE[6701] logger.c: [Jul 12 14:50:14]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:50:14] VERBOSE[6702] logger.c: [Jul 12 14:50:14]     -- Executing [050*198*250*114*78600051@default:1] Goto("Local/050*198*250*114*78600051@default-eff3,2", "default|78600051|1") in new stack
[Jul 12 14:50:14] VERBOSE[6702] logger.c: [Jul 12 14:50:14]     -- Goto (default,78600051,1)
[Jul 12 14:50:14] VERBOSE[6702] logger.c: [Jul 12 14:50:14]     -- Executing [78600051@default:1] MeetMe("Local/050*198*250*114*78600051@default-eff3,2", "8600051|Fq") in new stack
[Jul 12 14:50:14] VERBOSE[6701] logger.c: [Jul 12 14:50:14]        > Channel Local/050*198*250*114*78600051@default-eff3,1 was answered.
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]     -- Executing [83047777777777@vicidial-auto:1] Answer("Local/050*198*250*114*78600051@default-eff3,1", "") in new stack
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]     -- Executing [83047777777777@vicidial-auto:2] Playback("Local/050*198*250*114*78600051@default-eff3,1", "ding") in new stack
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]     -- <Local/050*198*250*114*78600051@default-eff3,1> Playing 'ding' (language 'en')
[Jul 12 14:50:14] WARNING[6703] file.c: Unexpected control subclass '-1'
[Jul 12 14:50:14] WARNING[6703] file.c: Unexpected control subclass '-1'
[Jul 12 14:50:14] VERBOSE[6701] logger.c: [Jul 12 14:50:14]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]     -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/050*198*250*114*78600051@default-eff3,1", "") in new stack
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]   == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/050*198*250*114*78600051@default-eff3,1'
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]     -- Executing [h@vicidial-auto:1] DeadAGI("Local/050*198*250*114*78600051@default-eff3,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 12 14:50:14] VERBOSE[6703] logger.c: [Jul 12 14:50:14]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 12 14:50:14] VERBOSE[6702] logger.c: [Jul 12 14:50:14]   == Spawn extension (default, 78600051, 1) exited non-zero on 'Local/050*198*250*114*78600051@default-eff3,2'
[Jul 12 14:50:14] VERBOSE[6702] logger.c: [Jul 12 14:50:14]     -- Executing [h@default:1] DeadAGI("Local/050*198*250*114*78600051@default-eff3,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 14:50:14] VERBOSE[6702] logger.c: [Jul 12 14:50:14]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Stopped music on hold on SIP/20 IN-00000005
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 12 14:50:15] ERROR[6539] utils.c: write() returned error: Broken pipe
[Jul 12 14:50:15] ERROR[6539] utils.c: write() returned error: Broken pipe
[Jul 12 14:50:15] ERROR[6539] utils.c: write() returned error: Broken pipe
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Executing [050*198*250*114*8600051@default:1] Goto("SIP/20 IN-00000005", "default|8600051|1") in new stack
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Goto (default,8600051,1)
[Jul 12 14:50:15] VERBOSE[6539] logger.c: [Jul 12 14:50:15]     -- Executing [8600051@default:1] MeetMe("SIP/20 IN-00000005", "8600051|F") in new stack
[Jul 12 14:51:02] VERBOSE[6776] logger.c: [Jul 12 14:51:02]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:51:02] VERBOSE[6776] logger.c: [Jul 12 14:51:02] Found
[Jul 12 14:51:02] VERBOSE[6776] logger.c: [Jul 12 14:51:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:51:02] VERBOSE[6782] logger.c: [Jul 12 14:51:02]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:51:02] VERBOSE[6782] logger.c: [Jul 12 14:51:02] Found
[Jul 12 14:51:02] VERBOSE[6782] logger.c: [Jul 12 14:51:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:51:02] VERBOSE[6776] logger.c: [Jul 12 14:51:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:51:03] VERBOSE[6782] logger.c: [Jul 12 14:51:03]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:51:07] VERBOSE[6788] logger.c: [Jul 12 14:51:07]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:51:07] VERBOSE[6788] logger.c: [Jul 12 14:51:07] Found
[Jul 12 14:51:07] VERBOSE[6788] logger.c: [Jul 12 14:51:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:51:07] VERBOSE[6788] logger.c: [Jul 12 14:51:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:51:50] VERBOSE[6524] logger.c: [Jul 12 14:51:50]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/20 IN-00000004'
[Jul 12 14:51:50] VERBOSE[6524] logger.c: [Jul 12 14:51:50]     -- Executing [h@default:1] DeadAGI("SIP/20 IN-00000004", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 14:51:50] VERBOSE[6524] logger.c: [Jul 12 14:51:50]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 14:52:01] VERBOSE[6867] logger.c: [Jul 12 14:52:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:52:01] VERBOSE[6867] logger.c: [Jul 12 14:52:01] Found
[Jul 12 14:52:01] VERBOSE[6867] logger.c: [Jul 12 14:52:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:52:01] VERBOSE[6868] logger.c: [Jul 12 14:52:01]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:52:01] VERBOSE[6868] logger.c: [Jul 12 14:52:01] Found
[Jul 12 14:52:01] VERBOSE[6868] logger.c: [Jul 12 14:52:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 12 14:52:01] VERBOSE[6868] logger.c: [Jul 12 14:52:01]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:52:02] VERBOSE[6867] logger.c: [Jul 12 14:52:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 12 14:52:04] VERBOSE[6539] logger.c: [Jul 12 14:52:04]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/20 IN-00000005'
[Jul 12 14:52:04] VERBOSE[6539] logger.c: [Jul 12 14:52:04]     -- Executing [h@default:1] DeadAGI("SIP/20 IN-00000005", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 12 14:52:04] VERBOSE[6539] logger.c: [Jul 12 14:52:04]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 12 14:52:06] VERBOSE[6879] logger.c: [Jul 12 14:52:06]   == Parsing '/etc/asterisk/manager.conf': [Jul 12 14:52:06] VERBOSE[6879] logger.c: [Jul 12 14:52:06] Found
[Jul 12 14:52:06] VERBOSE[6879] logger.c: [Jul 12 14:52:06]   == Manager 'sendcron' logged on from 127.0.0.1
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm

Re: Customer has hung up....

Postby necival » Tue Jul 16, 2013 1:18 pm

Thanks again for your help William.

I installed another vicibox, again same issue... This time the only thing I did was add the inbound / outbound trunks and create 1 user, 1 phone, 1 campaign, 1 list, 1 in-group.

I attempted vicibox 4.0.3, but the install screen was a scrambled on the LAN setup, so went back to 3.1.15.

ViciBox Redux v.3.1.15 release
VERSION: 2.8-408a
BUILD: 130711-2208
Asterisk 1.4.39.2-vici

Code: Select all
[Jul 16 14:07:27]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:07:27] Found
[Jul 16 14:07:27]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 16 14:07:30]     -- Executing [8600051@default:1] MeetMe("SIP/1000-0000000c", "8600051|F") in new stack
[Jul 16 14:07:30]   == Parsing '/etc/asterisk/meetme.conf': [Jul 16 14:07:30] Found
[Jul 16 14:07:30]   == Parsing '/etc/asterisk/meetme-vicidial.conf': [Jul 16 14:07:30] Found
[Jul 16 14:07:30]     -- Created MeetMe conference 1023 for conference '8600051'
[Jul 16 14:07:30]     -- <SIP/1000-0000000c> Playing 'conf-onlyperson' (language 'en')
[Jul 16 14:07:32]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:07:44]     -- Executing [19545052818@trunkinbound:1] AGI("SIP/20 IN-0000000d", "agi-DID_route.agi") in new stack
[Jul 16 14:07:44]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
[Jul 16 14:07:44]     -- AGI Script agi-DID_route.agi completed, returning 0
[Jul 16 14:07:44]     -- Executing [99909*2***DID@default:1] Answer("SIP/20 IN-0000000d", "") in new stack
[Jul 16 14:07:44]     -- Executing [99909*2***DID@default:2] AGI("SIP/20 IN-0000000d", "agi-VDAD_ALL_inbound.agi") in new stack
[Jul 16 14:07:44]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jul 16 14:07:45]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:45]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:46]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:07:46] Found
[Jul 16 14:07:46]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 16 14:07:46]     -- Executing [050*196*073*151*78600051@default:1] Goto("Local/050*196*073*151*78600051@default-1ffc,2", "default|78600051|1") in new stack
[Jul 16 14:07:46]     -- Goto (default,78600051,1)
[Jul 16 14:07:46]     -- Executing [78600051@default:1] MeetMe("Local/050*196*073*151*78600051@default-1ffc,2", "8600051|Fq") in new stack
[Jul 16 14:07:46]     -- Executing [83047777777777@vicidial-auto:1] Answer("Local/050*196*073*151*78600051@default-1ffc,1", "") in new stack
[Jul 16 14:07:46]     -- Executing [83047777777777@vicidial-auto:2] Playback("Local/050*196*073*151*78600051@default-1ffc,1", "ding") in new stack
[Jul 16 14:07:46]     -- <Local/050*196*073*151*78600051@default-1ffc,1> Playing 'ding' (language 'en')
[Jul 16 14:07:46] WARNING[11323]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Jul 16 14:07:46] WARNING[11323]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Jul 16 14:07:46]     -- Executing [83047777777777@vicidial-auto:3] Hangup("Local/050*196*073*151*78600051@default-1ffc,1", "") in new stack
[Jul 16 14:07:46]   == Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/050*196*073*151*78600051@default-1ffc,1'
[Jul 16 14:07:46]     -- Executing [h@vicidial-auto:1] DeadAGI("Local/050*196*073*151*78600051@default-1ffc,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Jul 16 14:07:46]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Jul 16 14:07:46]   == Spawn extension (default, 78600051, 1) exited non-zero on 'Local/050*196*073*151*78600051@default-1ffc,2'
[Jul 16 14:07:46]     -- Executing [h@default:1] DeadAGI("Local/050*196*073*151*78600051@default-1ffc,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 16 14:07:46]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 16 14:07:47]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:47]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:47]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:47]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:47]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:47]     -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jul 16 14:07:47] ERROR[11310]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 16 14:07:47] ERROR[11310]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 16 14:07:47] ERROR[11310]: utils.c:967 ast_carefulwrite: write() returned error: Broken pipe
[Jul 16 14:07:47]     -- AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
[Jul 16 14:07:47]     -- Executing [050*196*073*151*8600051@default:1] Goto("SIP/20 IN-0000000d", "default|8600051|1") in new stack
[Jul 16 14:07:47]     -- Goto (default,8600051,1)
[Jul 16 14:07:47]     -- Executing [8600051@default:1] MeetMe("SIP/20 IN-0000000d", "8600051|F") in new stack
[Jul 16 14:07:47]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:07:47] Found
[Jul 16 14:07:47]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 16 14:07:47]     -- Executing [58600051@default:1] MeetMe("Local/58600051@default-299f,2", "8600051|Fmq") in new stack
[Jul 16 14:07:47]     -- Executing [8309@default:1] Answer("Local/58600051@default-299f,1", "") in new stack
[Jul 16 14:07:47]     -- Executing [8309@default:2] Monitor("Local/58600051@default-299f,1", "wav|1000_1000_130716140746_3057987510") in new stack
[Jul 16 14:07:47]     -- Executing [8309@default:3] Wait("Local/58600051@default-299f,1", "3600") in new stack
[Jul 16 14:07:48]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:07:49]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:08:02]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:08:02] Found
[Jul 16 14:08:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 16 14:08:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:08:02]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:08:02] Found
[Jul 16 14:08:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 16 14:08:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:08:07]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:08:07] Found
[Jul 16 14:08:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Jul 16 14:08:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:08:08]   == Parsing '/etc/asterisk/manager.conf': [Jul 16 14:08:08] Found
[Jul 16 14:08:08]   == Manager 'sendcron' logged on from 127.0.0.1


Here is where I clicked "BLIND TRANSFER", system sends agent to dispo screen, but caller is still connected:
Code: Select all
[Jul 16 14:08:08]   == Spawn extension (default, 58600051, 1) exited non-zero on 'Local/58600051@default-299f,2'
[Jul 16 14:08:08]     -- Executing [h@default:1] DeadAGI("Local/58600051@default-299f,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 16 14:08:08]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 16 14:08:08]   == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600051@default-299f,1'
[Jul 16 14:08:08]     -- Executing [h@default:1] DeadAGI("Local/58600051@default-299f,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 16 14:08:08]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jul 16 14:08:10]   == Manager 'sendcron' logged off from 127.0.0.1
[Jul 16 14:08:33]   == Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/20 IN-0000000d'
[Jul 16 14:08:33]     -- Executing [h@default:1] DeadAGI("SIP/20 IN-0000000d", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jul 16 14:08:33]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0


On this install all outbound calls, transfer / park functions seem fine.
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm

Re: Customer has hung up....

Postby mattyou1985 » Tue Jul 12, 2016 11:07 am

necival wrote:By the way, "HANGUP CUSTOMER" works unless you try to use the transfer once you try transfer, hangup no longer works. "PARK CALL" also does nothing at all.

All of these functions work fine on outbound calls...

I installed a new machine lastnight and it has the exact same issue:

ViciBox Redux v.3.1.15 release
VERSION: 2.8-407a
BUILD: 130709-1350
Asterisk 1.4.39.2-vici

Thanks!

Updated info:
"DIAL WITH CUSTOMER" works! But not Blind Xfer or Park+Dial Xfer or Xfer to VM


viewtopic.php?f=2&t=35576

think this might help

i had to do this so when client had hung up agent gets a bleep and then a popup saying please hang up if this is not changed then the same notifacation will show but after 30 somthink/sec for me that was too long and now its instant

and i no thir is this "Customer Gone Warning Seconds:1" but its 1min and thats to long we found that are agents was chatting for that time wasting time chatting for nothink for a full min

so lets do the math 60 calls and waste 1min a call thats 1 houer for nothink not good so it had to be changed
best regards
mattyou1985
 
Posts: 111
Joined: Tue Apr 19, 2016 3:30 pm

Re: Customer has hung up....

Postby necival » Tue Jul 19, 2016 8:37 am

Thanks for the response, I ended up fixing the issue by updating to a newer version. 8)
necival
 
Posts: 23
Joined: Tue Jul 31, 2012 4:35 pm


Return to Support

Who is online

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