Survey Campaigns Drop Calls When Answered
Posted: Wed Sep 29, 2010 6:39 pm
I have been working with VicidialNOW with no problems for well over a year now. I go to use goautodial now that it's out of rc stage and start having problems immediately. I downloaded goautodial yesterday and successfully install on an AMD 64 3000+ system with 512 ddr ram. Connected to the SIP trunk just fine. Created a user and extension for the user to use. The soft phone connects just fine. The user logs in to the test campaign I created with my cell phone as the only number. The soft rings and when answered says there is currently only one person in the conference. So far so good.
At this point the campaign is set to manual dial. The user clicks to dial the next available number. My cell phone rings and i pick up. The user and i can hear each other just fine.
I set the campaign to ratio dialing and a ratio of 4 to 1. I reset the only lead in the database. Log the user out and have them log back in. My phone rings, I say "hello" and nothing else happens. I look at the asterisk logs, the default survey hello file was never played.
I went looking around for something to help me figure out what is happening. I found something about the audio store. I set that up. Tried the test survey again and got the same response. Looked around for more help, and found no one has had a problem with the survey campaign just hanging up on them. Doesn't quite surprise me.
I'm at a loss. Logs are below.
[Sep 29 19:09:17] -- SIP/sipgully-00000007 answered Local/91XXXXXXXXXX@default-04f1,2
[Sep 29 19:09:17] > Channel Local/91XXXXXXXXXX@default-04f1,1 was answered.
[Sep 29 19:09:17] -- Executing [8373@default:1] Playback("Local/91XXXXXXXXXX@default-04f1,1", "sip-silence") in new stack
[Sep 29 19:09:17] ERROR[11263]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Sep 29 19:09:17] ERROR[11263]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Sep 29 19:09:17] ERROR[11263]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Sep 29 19:09:17] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 29 19:09:17] -- <Local/91XXXXXXXXXX@default-04f1,1> Playing 'sip-silence' (language 'en')
[Sep 29 19:09:17] WARNING[11317]: file.c:1292 waitstream_core: Unexpected control subclass '-1'
[Sep 29 19:09:17] -- Executing [8373@default:2] AGI("Local/91XXXXXXXXXX@default-04f1,1", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 29 19:09:17] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 29 19:09:17] -- Executing [8373@default:3] AMD("Local/91XXXXXXXXXX@default-04f1,1", "2000|2000|1000|5000|120|50|4|256") in new stack
[Sep 29 19:09:17] -- AMD: Local/91XXXXXXXXXX@default-04f1,1 XXXXXXXXXX begin_of_the_skype_highlighting 1 XXXXXXXXXX end_of_the_skype_highlighting (null) (Fmt: 64)
[Sep 29 19:09:17] -- AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256]
[Sep 29 19:09:17] -- AMD: Changed state to STATE_IN_SILENCE
[Sep 29 19:09:18] -- AMD: Channel [Local/91XXXXXXXXXX@default-04f1,1]. Too long...
[Sep 29 19:09:18] -- AMD: Channel [Local/91XXXXXXXXXX@default-04f1,1]. Too long...
[Sep 29 19:09:18] -- Executing [8373@default:4] AGI("Local/91XXXXXXXXXX@default-04f1,1", "VD_amd.agi|8373") in new stack
[Sep 29 19:09:18] -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
[Sep 29 19:09:19] -- AGI Script VD_amd.agi completed, returning 0
[Sep 29 19:09:19] -- Executing [8373@default:5] AGI("Local/91XXXXXXXXXX@default-04f1,1", "agi-VDAD_ALL_outbound.agi|SURVEYCAMP-----LB") in new stack
[Sep 29 19:09:19] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Sep 29 19:09:20] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 29 19:09:20] -- Executing [8373@default:6] AGI("Local/91XXXXXXXXXX@default-04f1,1", "agi-VDAD_ALL_outbound.agi|SURVEYCAMP-----LB") in new stack
[Sep 29 19:09:20] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Sep 29 19:09:21] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 29 19:09:21] -- Executing [8373@default:7] Hangup("Local/91XXXXXXXXXX@default-04f1,1", "") in new stack
[Sep 29 19:09:21] == Spawn extension (default, 8373, 7) exited non-zero on 'Local/91XXXXXXXXXX@default-04f1,1'
[Sep 29 19:09:21] -- Executing [h@default:1] DeadAGI("Local/91XXXXXXXXXX@default-04f1,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Sep 29 19:09:21] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Sep 29 19:09:21] -- Executing [h@default:1] DeadAGI("Local/91XXXXXXXXXX@default-04f1,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----17-----4") in new stack
[Sep 29 19:09:22] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... --17-----4 completed, returning 0
[Sep 29 19:09:22] == Spawn extension (default, 91XXXXXXXXXX, 2) exited non-zero on 'Local/91XXXXXXXXXX@default-04f1,2'
At this point the campaign is set to manual dial. The user clicks to dial the next available number. My cell phone rings and i pick up. The user and i can hear each other just fine.
I set the campaign to ratio dialing and a ratio of 4 to 1. I reset the only lead in the database. Log the user out and have them log back in. My phone rings, I say "hello" and nothing else happens. I look at the asterisk logs, the default survey hello file was never played.
I went looking around for something to help me figure out what is happening. I found something about the audio store. I set that up. Tried the test survey again and got the same response. Looked around for more help, and found no one has had a problem with the survey campaign just hanging up on them. Doesn't quite surprise me.
I'm at a loss. Logs are below.
[Sep 29 19:09:17] -- SIP/sipgully-00000007 answered Local/91XXXXXXXXXX@default-04f1,2
[Sep 29 19:09:17] > Channel Local/91XXXXXXXXXX@default-04f1,1 was answered.
[Sep 29 19:09:17] -- Executing [8373@default:1] Playback("Local/91XXXXXXXXXX@default-04f1,1", "sip-silence") in new stack
[Sep 29 19:09:17] ERROR[11263]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Sep 29 19:09:17] ERROR[11263]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Sep 29 19:09:17] ERROR[11263]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Sep 29 19:09:17] == Manager 'sendcron' logged off from 127.0.0.1
[Sep 29 19:09:17] -- <Local/91XXXXXXXXXX@default-04f1,1> Playing 'sip-silence' (language 'en')
[Sep 29 19:09:17] WARNING[11317]: file.c:1292 waitstream_core: Unexpected control subclass '-1'
[Sep 29 19:09:17] -- Executing [8373@default:2] AGI("Local/91XXXXXXXXXX@default-04f1,1", "agi://127.0.0.1:4577/call_log") in new stack
[Sep 29 19:09:17] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Sep 29 19:09:17] -- Executing [8373@default:3] AMD("Local/91XXXXXXXXXX@default-04f1,1", "2000|2000|1000|5000|120|50|4|256") in new stack
[Sep 29 19:09:17] -- AMD: Local/91XXXXXXXXXX@default-04f1,1 XXXXXXXXXX begin_of_the_skype_highlighting 1 XXXXXXXXXX end_of_the_skype_highlighting (null) (Fmt: 64)
[Sep 29 19:09:17] -- AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256]
[Sep 29 19:09:17] -- AMD: Changed state to STATE_IN_SILENCE
[Sep 29 19:09:18] -- AMD: Channel [Local/91XXXXXXXXXX@default-04f1,1]. Too long...
[Sep 29 19:09:18] -- AMD: Channel [Local/91XXXXXXXXXX@default-04f1,1]. Too long...
[Sep 29 19:09:18] -- Executing [8373@default:4] AGI("Local/91XXXXXXXXXX@default-04f1,1", "VD_amd.agi|8373") in new stack
[Sep 29 19:09:18] -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
[Sep 29 19:09:19] -- AGI Script VD_amd.agi completed, returning 0
[Sep 29 19:09:19] -- Executing [8373@default:5] AGI("Local/91XXXXXXXXXX@default-04f1,1", "agi-VDAD_ALL_outbound.agi|SURVEYCAMP-----LB") in new stack
[Sep 29 19:09:19] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Sep 29 19:09:20] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 29 19:09:20] -- Executing [8373@default:6] AGI("Local/91XXXXXXXXXX@default-04f1,1", "agi-VDAD_ALL_outbound.agi|SURVEYCAMP-----LB") in new stack
[Sep 29 19:09:20] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Sep 29 19:09:21] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Sep 29 19:09:21] -- Executing [8373@default:7] Hangup("Local/91XXXXXXXXXX@default-04f1,1", "") in new stack
[Sep 29 19:09:21] == Spawn extension (default, 8373, 7) exited non-zero on 'Local/91XXXXXXXXXX@default-04f1,1'
[Sep 29 19:09:21] -- Executing [h@default:1] DeadAGI("Local/91XXXXXXXXXX@default-04f1,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Sep 29 19:09:21] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Sep 29 19:09:21] -- Executing [h@default:1] DeadAGI("Local/91XXXXXXXXXX@default-04f1,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----17-----4") in new stack
[Sep 29 19:09:22] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... --17-----4 completed, returning 0
[Sep 29 19:09:22] == Spawn extension (default, 91XXXXXXXXXX, 2) exited non-zero on 'Local/91XXXXXXXXXX@default-04f1,2'