vicidial.org

Hi,

I have installed Goautodial and Digium TE220,

VERSION: 2.2.1-237
BUILD: 100510-2015


I have E1 PRI's up.
I can make outbound call from Eyebeam
I can make outbound call from vicidial using Dial Method : Manual

But

I can NOT make outgoing calls when using Dial Method: Ratio.
The customer get the call and its hang up in 3-4 sec but I can not hear the customer and in Agent page, the Live Call image shows in green color and then off with No Live Call, the Admin page of vicidial shows call status 'Dead'.

please help

1) Have you changed the IP address of this server?

2) Show asterisk CLI from a TEST (not 3000 lines of live server code, please!) of the moments up to fail

3) Have a look at /var/log/astguiclient to see the decisionmaking process if the CLI doesn't show what is happening.

4) Thanks for posting your installation method, vicidial version and build#!!

[quote="williamconley"]1) Have you changed the IP address of this server?

yes, I have changed. its 10.128.210.50 (my local lan ip)

and after this I run update_server_ip also.
and in extensions.conf I add under [default]

exten => _010*128*210*050*.,1,Goto(default,${EXTEN:16},1)


2) Show asterisk CLI from a TEST (not 3000 lines of live server code, please!) of the moments up to fail

[Mar 18 01:14:52] == Manager 'sendcron' logged on from 127.0.0.1
[Mar 18 01:14:52] -- Executing [3454607437@default:1] AGI("Local/3454607437@ default-4e27,2", "agi://127.0.0.1:4577/call_log") in new stack
[Mar 18 01:14:52] -- AGI Script agi://127.0.0.1:4577/call_log completed, ret urning 0
[Mar 18 01:14:52] -- Executing [3454607437@default:2] Dial("Local/3454607437 @default-4e27,2", "DAHDI/g0/3454607437||Tto") in new stack
[Mar 18 01:14:52] -- Requested transfer capability: 0x00 - SPEECH
[Mar 18 01:14:52] -- Called g0/3454607437
[Mar 18 01:14:52] -- DAHDI/2-1 is proceeding passing it to Local/3454607437@ default-4e27,2
[Mar 18 01:14:55] == Manager 'sendcron' logged off from 127.0.0.1
[Mar 18 01:14:55] -- DAHDI/2-1 is ringing
[Mar 18 01:14:57] -- DAHDI/2-1 answered Local/3454607437@default-4e27,2
[Mar 18 01:14:57] > Channel Local/3454607437@default-4e27,1 was answered.
[Mar 18 01:14:57] -- Executing [8368@default:1] Playback("Local/3454607437@d efault-4e27,1", "sip-silence") in new stack
[Mar 18 01:14:57] == Manager 'sendcron' logged off from 127.0.0.1
[Mar 18 01:14:57] -- <Local/3454607437@default-4e27,1> Playing 'sip-silence' (language 'en')
[Mar 18 01:14:57] WARNING[32432]: file.c:1292 waitstream_core: Unexpected contro l subclass '-1'
[Mar 18 01:14:57] -- Executing [h@default:1] DeadAGI("Local/3454607437@defau lt-4e27,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---- -ANSWER-----5-----0") in new stack
[Mar 18 01:14:57] -- Executing [8368@default:2] AGI("DAHDI/2-1", "agi://127. 0.0.1:4577/call_log") in new stack
[Mar 18 01:14:57] -- AGI Script agi://127.0.0.1:4577/call_log completed, ret urning 0
[Mar 18 01:14:57] -- Executing [8368@default:3] AGI("DAHDI/2-1", "agi-VDAD_A LL_outbound.agi|NORMAL-----LB") in new stack
[Mar 18 01:14:57] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ ALL_outbound.agi
[Mar 18 01:14:57] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI -----NODEBUG-----16-----ANSWER-----5-----0 completed, returning 0
[Mar 18 01:14:57] == Spawn extension (default, 3454607437, 2) exited non-zero on 'Local/3454607437@default-4e27,2'


3) Have a look at /var/log/astguiclient to see the decisionmaking process if the CLI doesn't show what is happening.

Which file you want me to see here, too many files are here, I see this one 'vdautodial.2011-03-18'

and what does this means > 2011-03-18 00:00:30|LOCAL TRUNK SHORTAGE: 0|0 (1 - 96)|.


4) Thanks for posting your installation method, vicidial version and build#!!

thanks for your propmt response and great help. keep it up

since you are using dahdi channels, i'm not sure it's appropriate to have the "sip-silence" line in there.

There's nothing wrong with having the sip-silence in there on PRIs

gurugee1 wrote:[Mar 18 01:14:57] -- <Local/3454607437@default-4e27,1> Playing 'sip-silence' (language 'en')
[Mar 18 01:14:57] WARNING[32432]: file.c:1292 waitstream_core: Unexpected contro l subclass '-1'
[Mar 18 01:14:57] -- Executing [h@default:1] DeadAGI("Local/3454607437@defau lt-4e27,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---- -ANSWER-----5-----0") in new stack

in that case, something happens between playing the silence and hanging up the call. some form of conflict will have to be in zap or agi debug somewhere that causes the hangup.

Thanks Everyone, My issue is resloved.
I was adding this line ---
exten => _010*128*210*050*.,1,Goto(default,${EXTEN:16},1)

under the [trunkinbound] but when I paste it under [default] it start working.

Once agin, thanks to all help and time.

I'm not getting the calls recordings,
I select from users and campaings, Record all Calls Forcely, but still no call recording is coming.

please help again.

thank you

didn't you already create a sep post for this?

Yes - I do.

and I post here also, so everyone knows, what i m using and whats the case history.

please help - thank you

in that case you should have listed the link to the new post so answers would not be given in two places. crossposting/doubleposting is not proper forum etiquette

Okay Sir

I apologies, I don't know about it.

live and learn

from yesterday, I'm having no calls issue after 40-45min of intervals.
The agents are not getting calls. but in admin panel of vicidial, I can see 70-80 calls are being placed. It was working fine since 4 months, I did not make any change in it.
Now :

1) When I restart the server it start working
2) When I unplug and plug the PRI cables from digium TE220 card, it start working.
3) When I wait for atleast 4-5min, agents start getting the calls.

Here is the CLI output with debug on (WHEN ITS WORKING FINE)

ug 12 14:03:58] -- Processing IE 41 (cs0, Date/Time)
[Aug 12 14:03:58] q931.c:3626 q931_receive: call 38333 on channel 4 enters state 10 (Active)
[Aug 12 14:03:58] > Protocol Discriminator: Q.931 (8) len=5
[Aug 12 14:03:58] > Call Ref: len= 2 (reference 5565/0x15BD) (Originator)
[Aug 12 14:03:58] > Message type: CONNECT ACKNOWLEDGE (15)
[Aug 12 14:03:58] -- DAHDI/35-1 answered Local/923448463642@default-b93c,2
[Aug 12 14:03:58] > Channel Local/923448463642@default-b93c,1 was answered.
[Aug 12 14:03:58] ERROR[15158]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:58] ERROR[15158]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:58] ERROR[15158]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:58] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:03:58] -- Executing [8368@default:1] Playback("Local/923448463642@default-b93c,1", "sip-silence") in new stack
[Aug 12 14:03:58] -- <Local/923448463642@default-b93c,1> Playing 'sip-silence' (language 'en')
[Aug 12 14:03:58] WARNING[15939]: file.c:1292 waitstream_core: Unexpected control subclass '-1'
[Aug 12 14:03:58] -- Executing [h@default:1] DeadAGI("Local/923448463642@default-b93c,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----13-----0") in new stack
[Aug 12 14:03:58] -- Executing [8368@default:2] AGI("DAHDI/35-1", "agi://127.0.0.1:4577/call_log") in new stack
[Aug 12 14:03:58] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Aug 12 14:03:58] -- Executing [8368@default:3] AGI("DAHDI/35-1", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack
[Aug 12 14:03:58] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... --13-----0 completed, returning 0
[Aug 12 14:03:58] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Aug 12 14:03:58] == Spawn extension (default, 923448463642, exited non-zero on 'Local/923448463642@default-b93c,2'
[Aug 12 14:03:59] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:03:59] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:03:59] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:03:59] Timed out looking for release
[Aug 12 14:03:59] q931.c:2967 q931_release: call 38697 on channel 10 enters state 19 (Release Request)
[Aug 12 14:03:59] > Protocol Discriminator: Q.931 (8) len=9
[Aug 12 14:03:59] > Call Ref: len= 2 (reference 5929/0x1729) (Originator)
[Aug 12 14:03:59] > Message type: RELEASE (77)
[Aug 12 14:03:59] > [08 02 81 90]
[Aug 12 14:03:59] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Aug 12 14:03:59] > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[Aug 12 14:03:59] < Protocol Discriminator: Q.931 (8) len=12
[Aug 12 14:03:59] < Call Ref: len= 2 (reference 5559/0x15B7) (Terminator)
[Aug 12 14:03:59] < Message type: CONNECT (7)
[Aug 12 14:03:59] < [29 05 0b 08 0c 0e 03]
[Aug 12 14:03:59] < Time Date (len= 7) [ [Aug 12 14:03:59] 11[Aug 12 14:03:59] -08[Aug 12 14:03:59] -12[Aug 12 14:03:59] 14[Aug 12 14:03:59] :03[Aug 12 14:03:59] ]
[Aug 12 14:03:59] -- Processing IE 41 (cs0, Date/Time)
[Aug 12 14:03:59] q931.c:3626 q931_receive: call 38327 on channel 13 enters state 10 (Active)
[Aug 12 14:03:59] > Protocol Discriminator: Q.931 (8) len=5
[Aug 12 14:03:59] > Call Ref: len= 2 (reference 5559/0x15B7) (Originator)
[Aug 12 14:03:59] > Message type: CONNECT ACKNOWLEDGE (15)
[Aug 12 14:03:59] -- DAHDI/44-1 answered Local/923448950792@default-61cd,2
[Aug 12 14:03:59] > Channel Local/923448950792@default-61cd,1 was answered.
[Aug 12 14:03:59] ERROR[14268]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:59] ERROR[14268]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:59] ERROR[14268]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:59] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:03:59] -- Executing [8368@default:1] Playback("Local/923448950792@default-61cd,1", "sip-silence") in new stack
[Aug 12 14:03:59] -- <Local/923448950792@default-61cd,1> Playing 'sip-silence' (language 'en')
[Aug 12 14:03:59] WARNING[15961]: file.c:1292 waitstream_core: Unexpected control subclass '-1'
[Aug 12 14:03:59] -- Executing [h@default:1] DeadAGI("Local/923448950792@default-61cd,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----28-----0") in new stack
[Aug 12 14:03:59] -- Executing [8368@default:2] AGI("DAHDI/44-1", "agi://127.0.0.1:4577/call_log") in new stack
[Aug 12 14:03:59] ERROR[15939]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:59] ERROR[15939]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:03:59] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Aug 12 14:03:59] -- Executing [010*128*210*050*8600051@default:1] Goto("DAHDI/35-1", "default|8600051|1") in new stack
[Aug 12 14:03:59] -- Goto (default,8600051,1)
[Aug 12 14:03:59] -- Executing [8600051@default:1] MeetMe("DAHDI/35-1", "8600051|F") in new stack
[Aug 12 14:03:59] < Protocol Discriminator: Q.931 (8) len=17
[Aug 12 14:03:59] < Call Ref: len= 2 (reference 5566/0x15BE) (Terminator)
[Aug 12 14:03:59] < Message type: ALERTING (1)
[Aug 12 14:03:59] < [1e 02 82 88]
[Aug 12 14:03:59] < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
[Aug 12 14:03:59] < Ext: 1 Progress Description: Inband information or appropriate pattern now available. (8) ]
[Aug 12 14:03:59] < [1e 02 82 82]
[Aug 12 14:03:59] < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Public network serving the local user (2)
[Aug 12 14:03:59] < Ext: 1 Progress Description: Called equipment is non-ISDN. (2) ]
[Aug 12 14:03:59] < [76 02 00 a0]
[Aug 12 14:03:59] < IE: Redirection Number (len = 4)
[Aug 12 14:03:59] -- Processing IE 30 (cs0, Progress Indicator)
[Aug 12 14:03:59] -- Processing IE 30 (cs0, Progress Indicator)
[Aug 12 14:03:59] -- Processing IE 118 (cs0, Redirection Number)
[Aug 12 14:03:59] q931.c:3596 q931_receive: call 38334 on channel 11 enters state 4 (Call Delivered)

WHEN THERE IS PROBLEM - I GOT THIS ON CLI

NCEL----------") in new stack
[Aug 12 14:48:01] ERROR[29336]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] ERROR[29336]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:48:01] NEW_HANGUP DEBUG: Calling q931_hangup, ourstate Call Initiated, peerstate Overlap sending
[Aug 12 14:48:01] q931.c:3015 q931_disconnect: call 39446 on channel 19 enters state 11 (Disconnect Request)
[Aug 12 14:48:01] > Protocol Discriminator: Q.931 (8) len=9
[Aug 12 14:48:01] > Call Ref: len= 2 (reference 6678/0x1A16) (Originator)
[Aug 12 14:48:01] > Message type: DISCONNECT (69)
[Aug 12 14:48:01] > [08 02 81 90]
[Aug 12 14:48:01] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Aug 12 14:48:01] > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[Aug 12 14:48:01] ERROR[29337]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] ERROR[29337]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] ERROR[29337]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:48:01] -- Hungup 'DAHDI/50-1'
[Aug 12 14:48:01] == Spawn extension (default, 923444867511, exited non-zero on 'Local/923444867511@default-92ad,2'
[Aug 12 14:48:01] -- Executing [h@default:1] DeadAGI("Local/923444867511@default-92ad,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
[Aug 12 14:48:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Aug 12 14:48:01] == End MixMonitor Recording Local/923444667679@default-becc,2
[Aug 12 14:48:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Aug 12 14:48:01] == End MixMonitor Recording Local/923444867511@default-92ad,2
[Aug 12 14:48:01] Timed out looking for release complete
[Aug 12 14:48:01] > Protocol Discriminator: Q.931 (8) len=9
[Aug 12 14:48:01] > Call Ref: len= 2 (reference 6664/0x1A08) (Originator)
[Aug 12 14:48:01] > Message type: RELEASE (77)
[Aug 12 14:48:01] > [08 02 81 90]
[Aug 12 14:48:01] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Aug 12 14:48:01] > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[Aug 12 14:48:01] -- Hungup 'DAHDI/66-1'
[Aug 12 14:48:01] ERROR[29348]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] == Spawn extension (default, 923443162182, exited non-zero on 'Local/923443162182@default-0b58,2'
[Aug 12 14:48:01] ERROR[29348]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] ERROR[29348]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] -- Executing [h@default:1] DeadAGI("Local/923443162182@default-0b58,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
[Aug 12 14:48:01] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:48:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Aug 12 14:48:01] == End MixMonitor Recording Local/923443162182@default-0b58,2
[Aug 12 14:48:01] Timed out looking for release complete
[Aug 12 14:48:01] > Protocol Discriminator: Q.931 (8) len=9
[Aug 12 14:48:01] > Call Ref: len= 2 (reference 6667/0x1A0B) (Originator)
[Aug 12 14:48:01] > Message type: RELEASE (77)
[Aug 12 14:48:01] > [08 02 81 90]
[Aug 12 14:48:01] > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
[Aug 12 14:48:01] > Ext: 1 Cause: Normal Clearing (16), class = Normal Event (1) ]
[Aug 12 14:48:01] -- Hungup 'DAHDI/67-1'
[Aug 12 14:48:01] == Spawn extension (default, 923444946728, exited non-zero on 'Local/923444946728@default-b908,2'
[Aug 12 14:48:01] -- Executing [h@default:1] DeadAGI("Local/923444946728@default-b908,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
[Aug 12 14:48:01] ERROR[29354]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] ERROR[29354]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] ERROR[29354]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe
[Aug 12 14:48:01] == Manager 'sendcron' logged off from 127.0.0.1
[Aug 12 14:48:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... --NODEBUG-

How often do you reboot your server?

Whenever the problem comes I do and it fixed the issue.

one more thing want to share, why i'm gettinh this on my cli

-- Executing [h@default:1] DeadAGI("Local/58600081@default-22 83,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG- ----0---------------") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI--- --NODEBUG-----0--------------- completed, returning 0
== Spawn extension (default, 8309, 3) exited non-zero on 'Local /58600081@default-2283,1'
-- Executing [h@default:1] DeadAGI("Local/58600081@default-22 83,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG- ----0---------------") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI--- --NODEBUG-----0--------------- completed, returning 0
-- B-channel 0/1 successfully restarted on span 2
-- B-channel 0/7 successfully restarted on span 2
-- B-channel 0/8 successfully restarted on span 2
-- B-channel 0/9 successfully restarted on span 2
-- B-channel 0/11 successfully restarted on span 2
-- B-channel 0/13 successfully restarted on span 2
-- B-channel 0/14 successfully restarted on span 2
-- B-channel 0/15 successfully restarted on span 2
-- B-channel 0/23 successfully restarted on span 2
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing [923423658636@default:1] Set("Local/923423658636 @default-5d67,2", "MONITOR_FILENAME=V0813122348003265093__2011081 3-122348") in new stack
-- Executing [923423658636@default:2] NoOp("Local/92342365863 6@default-5d67,2", "Caller Id is = ") in new stack
-- Executing [923423658636@default:3] MixMonitor("Local/92342 3658636@default-5d67,2", "V0813122348003265093__20110813-122348.w

How often do you reboot your server?

Whenever there is issue, I reboot the server .

So ... if you "only reboot when there is a problem", how often is that? (every 20 minutes? once a year?) gimme a hint here.

Thankful to Striker and all,

There was some issue in my 1 PRI, which is up and down.(flapping).
When I remove this pri, my issue is resolved.