Vicidial calls customer, hangs up on transfer
Posted: Wed Sep 13, 2006 12:55 pm
I'm having a problem getting calls connected to the agent. Vicidial correctly calls the agent and places him in a conference room, begins placing outbound calls, but the callee is hungup as soon as they answer. Looks like the transfer is failing, but I can't figure out why. I've tried setting up a new virgin dialplan, but the problem persists leading me to believe there's something else going on.
Sep 13 13:31:50 DEBUG[5606] manager.c: Manager received command 'Originate'
Sep 13 13:31:50 VERBOSE[5608] logger.c: -- Executing Dial("Local/914085551234@default-030d,2", "IAX2/myvoipprovider/14085551234||tTo") in new stack
Sep 13 13:31:50 DEBUG[30708] channel.c: Avoiding initial deadlock for 'IAX2/myvoipprovider-4'
Sep 13 13:31:50 VERBOSE[5608] logger.c: -- Called myvoipprovider/14085551234
Sep 13 13:31:50 VERBOSE[30758] logger.c: -- Call accepted by 10.10.10.10 (format gsm)
Sep 13 13:31:50 VERBOSE[30758] logger.c: -- Format for call is gsm
Sep 13 13:31:50 VERBOSE[5608] logger.c: -- IAX2/myvoipprovider-4 is making progress passing it to Local/914085551234@default-030d,2
Sep 13 13:31:50 DEBUG[30758] chan_iax2.c: Ooh, voice format changed to 2
Sep 13 13:31:50 DEBUG[30964] manager.c: Manager received command 'Command'
Sep 13 13:31:57 DEBUG[30964] manager.c: Manager received command 'Command'
Sep 13 13:31:57 VERBOSE[5608] logger.c: -- IAX2/myvoipprovider-4 answered Local/914085551234@default-030d,2
Sep 13 13:31:57 DEBUG[5606] manager.c: Manager received command 'Logoff'
Sep 13 13:31:57 VERBOSE[5606] logger.c: == Manager 'sendcron' logged off from 127.0.0.1
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("Local/914085551234@default-030d,1", "call_log.agi|8365") in new stack
Sep 13 13:31:57 DEBUG[5608] channel.c: Planning to masquerade channel IAX2/myvoipprovider-4 into the structure of Local/914085551234@default-030d,1
Sep 13 13:31:57 DEBUG[5608] channel.c: Done planning to masquerade channel IAX2/myvoipprovider-4 into the structure of Local/914085551234@default-030d,1
Sep 13 13:31:57 DEBUG[5608] chan_local.c: Not posting to queue since already masked on 'Local/914085551234@default-030d,2'
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 13 13:31:57 DEBUG[5631] channel.c: Got clone lock for masquerade on 'IAX2/myvoipprovider-4' at 0x9709a64
Sep 13 13:31:57 DEBUG[5608] channel.c: Didn't get a frame from channel: Local/914085551234@default-030d,2
Sep 13 13:31:57 DEBUG[5608] channel.c: Bridge stops bridging channels Local/914085551234@default-030d,2 and IAX2/myvoipprovider-4<MASQ>
Sep 13 13:31:57 DEBUG[5631] channel.c: Putting channel IAX2/myvoipprovider-4 in 64/64 formats
Sep 13 13:31:57 DEBUG[5631] channel.c: Released clone lock on 'Local/914085551234@default-030d,1<ZOMBIE>'
Sep 13 13:31:57 DEBUG[5608] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Sep 13 13:31:57 VERBOSE[5608] logger.c: == Spawn extension (default, 914085551234, 1) exited non-zero on 'Local/914085551234@default-030d,2'
Sep 13 13:31:57 DEBUG[5631] channel.c: Done Masquerading IAX2/myvoipprovider-4 (6)
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("IAX2/myvoipprovider-4", "agi-VDADtransfer.agi|8365") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("IAX2/myvoipprovider-4", "agi-VDADtransfer.agi|8365") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 13 13:31:57 DEBUG[30964] manager.c: Manager received command 'Command'
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("IAX2/myvoipprovider-4", "agi-VDADtransfer.agi|8365") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing Hangup("IAX2/myvoipprovider-4", "") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: == Spawn extension (default, 8365, 5) exited non-zero on 'IAX2/myvoipprovider-4'
Sep 13 13:31:57 DEBUG[5631] chan_iax2.c: We're hanging up IAX2/myvoipprovider-4 now...
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Hungup 'IAX2/myvoipprovider-4'
Sep 13 13:31:50 DEBUG[5606] manager.c: Manager received command 'Originate'
Sep 13 13:31:50 VERBOSE[5608] logger.c: -- Executing Dial("Local/914085551234@default-030d,2", "IAX2/myvoipprovider/14085551234||tTo") in new stack
Sep 13 13:31:50 DEBUG[30708] channel.c: Avoiding initial deadlock for 'IAX2/myvoipprovider-4'
Sep 13 13:31:50 VERBOSE[5608] logger.c: -- Called myvoipprovider/14085551234
Sep 13 13:31:50 VERBOSE[30758] logger.c: -- Call accepted by 10.10.10.10 (format gsm)
Sep 13 13:31:50 VERBOSE[30758] logger.c: -- Format for call is gsm
Sep 13 13:31:50 VERBOSE[5608] logger.c: -- IAX2/myvoipprovider-4 is making progress passing it to Local/914085551234@default-030d,2
Sep 13 13:31:50 DEBUG[30758] chan_iax2.c: Ooh, voice format changed to 2
Sep 13 13:31:50 DEBUG[30964] manager.c: Manager received command 'Command'
Sep 13 13:31:57 DEBUG[30964] manager.c: Manager received command 'Command'
Sep 13 13:31:57 VERBOSE[5608] logger.c: -- IAX2/myvoipprovider-4 answered Local/914085551234@default-030d,2
Sep 13 13:31:57 DEBUG[5606] manager.c: Manager received command 'Logoff'
Sep 13 13:31:57 VERBOSE[5606] logger.c: == Manager 'sendcron' logged off from 127.0.0.1
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("Local/914085551234@default-030d,1", "call_log.agi|8365") in new stack
Sep 13 13:31:57 DEBUG[5608] channel.c: Planning to masquerade channel IAX2/myvoipprovider-4 into the structure of Local/914085551234@default-030d,1
Sep 13 13:31:57 DEBUG[5608] channel.c: Done planning to masquerade channel IAX2/myvoipprovider-4 into the structure of Local/914085551234@default-030d,1
Sep 13 13:31:57 DEBUG[5608] chan_local.c: Not posting to queue since already masked on 'Local/914085551234@default-030d,2'
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 13 13:31:57 DEBUG[5631] channel.c: Got clone lock for masquerade on 'IAX2/myvoipprovider-4' at 0x9709a64
Sep 13 13:31:57 DEBUG[5608] channel.c: Didn't get a frame from channel: Local/914085551234@default-030d,2
Sep 13 13:31:57 DEBUG[5608] channel.c: Bridge stops bridging channels Local/914085551234@default-030d,2 and IAX2/myvoipprovider-4<MASQ>
Sep 13 13:31:57 DEBUG[5631] channel.c: Putting channel IAX2/myvoipprovider-4 in 64/64 formats
Sep 13 13:31:57 DEBUG[5631] channel.c: Released clone lock on 'Local/914085551234@default-030d,1<ZOMBIE>'
Sep 13 13:31:57 DEBUG[5608] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Sep 13 13:31:57 VERBOSE[5608] logger.c: == Spawn extension (default, 914085551234, 1) exited non-zero on 'Local/914085551234@default-030d,2'
Sep 13 13:31:57 DEBUG[5631] channel.c: Done Masquerading IAX2/myvoipprovider-4 (6)
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("IAX2/myvoipprovider-4", "agi-VDADtransfer.agi|8365") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("IAX2/myvoipprovider-4", "agi-VDADtransfer.agi|8365") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 13 13:31:57 DEBUG[30964] manager.c: Manager received command 'Command'
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing AGI("IAX2/myvoipprovider-4", "agi-VDADtransfer.agi|8365") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Executing Hangup("IAX2/myvoipprovider-4", "") in new stack
Sep 13 13:31:57 VERBOSE[5631] logger.c: == Spawn extension (default, 8365, 5) exited non-zero on 'IAX2/myvoipprovider-4'
Sep 13 13:31:57 DEBUG[5631] chan_iax2.c: We're hanging up IAX2/myvoipprovider-4 now...
Sep 13 13:31:57 VERBOSE[5631] logger.c: -- Hungup 'IAX2/myvoipprovider-4'