Dial Plan issue
Posted: Mon Nov 25, 2013 4:42 pm
Ok, I am having to dial at way to many lines to keep my agents connected, and i been trying to figure out what is going on for a while, so i am now just going to post this in hopes that someone will see something i do not see.
Warning; CLI spam will be below:
But let me start with my dial plan;
[xcast]
host=38.102.250.50
type=friend
disallow=all
allow=ulaw
trustrpid=yes
sendrpid=yes
qualify=yes
insecure=port,invite
dtmfmode=rfc2833
canreinvite=no
nat=no
host=38.102.250.60
context=trunkinbound
exten=> _81XXXXXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log)
exten=> _81XXXXXXXXXX,2,Dial(SIP/Xcast/${EXTEN:1},,tTr)
exten=> _81XXXXXXXXXX,3,Hangup
_________________________________________________________________________________________________________________________________
CLI: spam warning incoming ;
Verbosity is at least 21
[Nov 25 16:19:45] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:45] -- Got SIP response 503 "Carrier Service Unavailable" back from 38.102.250.60
[Nov 25 16:19:45] -- SIP/Xcast-00000350 is circuit-busy
[Nov 25 16:19:45] == Everyone is busy/congested at this time (1:0/1/0)
[Nov 25 16:19:45] -- Executing [818037710630@default:3] Hangup("Local/818037710630@default-d448,2", "") in new stack
[Nov 25 16:19:45] == Spawn extension (default, 818037710630, 3) exited non-zero on 'Local/818037710630@default-d448,2'
[Nov 25 16:19:45] -- Executing [h@default:1] DeadAGI("Local/818037710630@default-d448,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----34-----CONGESTION----------") in new stack
[Nov 25 16:19:46] -- AMD: Changed state to STATE_IN_SILENCE
[Nov 25 16:19:46] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:46] -- SIP/Xcast-00000355 is making progress passing it to Local/815734495464@default-7a70,2
[Nov 25 16:19:46] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:46] -- SIP/Xcast-00000381 is making progress passing it to Local/818037544137@default-678a,2
[Nov 25 16:19:46] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:46] -- SIP/Xcast-00000375 is making progress passing it to Local/818037507794@default-371a,2
[Nov 25 16:19:46] -- SIP/Xcast-0000037e is making progress passing it to Local/818034259366@default-d1e7,2
[Nov 25 16:19:46] -- SIP/Xcast-00000373 is making progress passing it to Local/818037547262@default-204d,2
[Nov 25 16:19:46] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] -- AMD: HUMAN: silenceDuration:1000 afterGreetingSilence:1000
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] -- Executing [8369@default:4] AGI("SIP/Xcast-00000337", "VD_amd.agi|8369") in new stack
[Nov 25 16:19:47] -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
[Nov 25 16:19:47] -- Executing [815737565929@default:1] AGI("Local/815737565929@default-8dfd,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [815737565929@default:2] Dial("Local/815737565929@default-8dfd,2", "SIP/Xcast/15737565929||tTr") in new stack
[Nov 25 16:19:47] -- Executing [818037351080@default:1] AGI("Local/818037351080@default-1a45,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- Called Xcast/15737565929
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [818037351080@default:2] Dial("Local/818037351080@default-1a45,2", "SIP/Xcast/18037351080||tTr") in new stack
[Nov 25 16:19:47] -- Called Xcast/18037351080
[Nov 25 16:19:47] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] -- Executing [818037918011@default:1] AGI("Local/818037918011@default-c6e2,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- SIP/Xcast-00000378 is making progress passing it to Local/818032524529@default-0bc1,2
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- SIP/Xcast-0000037d is making progress passing it to Local/818038293094@default-8040,2
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [818037918011@default:2] Dial("Local/818037918011@default-c6e2,2", "SIP/Xcast/18037918011||tTr") in new stack
[Nov 25 16:19:47] -- Called Xcast/18037918011
[Nov 25 16:19:47] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] -- Executing [818037546631@default:1] AGI("Local/818037546631@default-63bb,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [818037546631@default:2] Dial("Local/818037546631@default-63bb,2", "SIP/Xcast/18037546631||tTr") in new stack
[Nov 25 16:19:47] -- Called Xcast/18037546631
[Nov 25 16:19:48] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:48] Found
[Nov 25 16:19:48] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:48] -- SIP/Xcast-00000386 is ringing
[Nov 25 16:19:48] -- Executing [815736742995@default:1] AGI("Local/815736742995@default-237d,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:48] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:48] Found
[Nov 25 16:19:48] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:48] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:48] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:48] -- Executing [815736742995@default:2] Dial("Local/815736742995@default-237d,2", "SIP/Xcast/15736742995||tTr") in new stack
[Nov 25 16:19:48] -- Executing [815734315279@default:1] AGI("Local/815734315279@default-3736,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:48] -- Called Xcast/15736742995
[Nov 25 16:19:48] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:48] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:48] -- Executing [815734315279@default:2] Dial("Local/815734315279@default-3736,2", "SIP/Xcast/15734315279||tTr") in new stack
[Nov 25 16:19:48] -- Called Xcast/15734315279
[Nov 25 16:19:48] WARNING[8140]: rtp.c:953 ast_rtcp_read: RTCP Read too short
[Nov 25 16:19:48] -- Got SIP response 503 "Carrier Service Unavailable" back from 38.102.250.60
[Nov 25 16:19:48] -- SIP/Xcast-00000372 is circuit-busy
[Nov 25 16:19:48] == Everyone is busy/congested at this time (1:0/1/0)
[Nov 25 16:19:48] -- Executing [815732213732@default:3] Hangup("Local/815732213732@default-1378,2", "") in new stack
[Nov 25 16:19:48] == Spawn extension (default, 815732213732, 3) exited non-zero on 'Local/815732213732@default-1378,2'
Warning; CLI spam will be below:
But let me start with my dial plan;
[xcast]
host=38.102.250.50
type=friend
disallow=all
allow=ulaw
trustrpid=yes
sendrpid=yes
qualify=yes
insecure=port,invite
dtmfmode=rfc2833
canreinvite=no
nat=no
host=38.102.250.60
context=trunkinbound
exten=> _81XXXXXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log)
exten=> _81XXXXXXXXXX,2,Dial(SIP/Xcast/${EXTEN:1},,tTr)
exten=> _81XXXXXXXXXX,3,Hangup
_________________________________________________________________________________________________________________________________
CLI: spam warning incoming ;
Verbosity is at least 21
[Nov 25 16:19:45] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:45] -- Got SIP response 503 "Carrier Service Unavailable" back from 38.102.250.60
[Nov 25 16:19:45] -- SIP/Xcast-00000350 is circuit-busy
[Nov 25 16:19:45] == Everyone is busy/congested at this time (1:0/1/0)
[Nov 25 16:19:45] -- Executing [818037710630@default:3] Hangup("Local/818037710630@default-d448,2", "") in new stack
[Nov 25 16:19:45] == Spawn extension (default, 818037710630, 3) exited non-zero on 'Local/818037710630@default-d448,2'
[Nov 25 16:19:45] -- Executing [h@default:1] DeadAGI("Local/818037710630@default-d448,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----34-----CONGESTION----------") in new stack
[Nov 25 16:19:46] -- AMD: Changed state to STATE_IN_SILENCE
[Nov 25 16:19:46] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:46] -- SIP/Xcast-00000355 is making progress passing it to Local/815734495464@default-7a70,2
[Nov 25 16:19:46] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:46] -- SIP/Xcast-00000381 is making progress passing it to Local/818037544137@default-678a,2
[Nov 25 16:19:46] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:46] -- SIP/Xcast-00000375 is making progress passing it to Local/818037507794@default-371a,2
[Nov 25 16:19:46] -- SIP/Xcast-0000037e is making progress passing it to Local/818034259366@default-d1e7,2
[Nov 25 16:19:46] -- SIP/Xcast-00000373 is making progress passing it to Local/818037547262@default-204d,2
[Nov 25 16:19:46] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] -- AMD: HUMAN: silenceDuration:1000 afterGreetingSilence:1000
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] == Manager 'sendcron' logged off from 127.0.0.1
[Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] -- Executing [8369@default:4] AGI("SIP/Xcast-00000337", "VD_amd.agi|8369") in new stack
[Nov 25 16:19:47] -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
[Nov 25 16:19:47] -- Executing [815737565929@default:1] AGI("Local/815737565929@default-8dfd,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [815737565929@default:2] Dial("Local/815737565929@default-8dfd,2", "SIP/Xcast/15737565929||tTr") in new stack
[Nov 25 16:19:47] -- Executing [818037351080@default:1] AGI("Local/818037351080@default-1a45,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- Called Xcast/15737565929
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [818037351080@default:2] Dial("Local/818037351080@default-1a45,2", "SIP/Xcast/18037351080||tTr") in new stack
[Nov 25 16:19:47] -- Called Xcast/18037351080
[Nov 25 16:19:47] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] -- Executing [818037918011@default:1] AGI("Local/818037918011@default-c6e2,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- SIP/Xcast-00000378 is making progress passing it to Local/818032524529@default-0bc1,2
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- SIP/Xcast-0000037d is making progress passing it to Local/818038293094@default-8040,2
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [818037918011@default:2] Dial("Local/818037918011@default-c6e2,2", "SIP/Xcast/18037918011||tTr") in new stack
[Nov 25 16:19:47] -- Called Xcast/18037918011
[Nov 25 16:19:47] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:47] Found
[Nov 25 16:19:47] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:47] -- Executing [818037546631@default:1] AGI("Local/818037546631@default-63bb,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:47] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:47] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:47] -- Executing [818037546631@default:2] Dial("Local/818037546631@default-63bb,2", "SIP/Xcast/18037546631||tTr") in new stack
[Nov 25 16:19:47] -- Called Xcast/18037546631
[Nov 25 16:19:48] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:48] Found
[Nov 25 16:19:48] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:48] -- SIP/Xcast-00000386 is ringing
[Nov 25 16:19:48] -- Executing [815736742995@default:1] AGI("Local/815736742995@default-237d,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:48] == Parsing '/etc/asterisk/manager.conf': [Nov 25 16:19:48] Found
[Nov 25 16:19:48] == Manager 'sendcron' logged on from 127.0.0.1
[Nov 25 16:19:48] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:48] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:48] -- Executing [815736742995@default:2] Dial("Local/815736742995@default-237d,2", "SIP/Xcast/15736742995||tTr") in new stack
[Nov 25 16:19:48] -- Executing [815734315279@default:1] AGI("Local/815734315279@default-3736,2", "agi://127.0.0.1:4577/call_log") in new stack
[Nov 25 16:19:48] -- Called Xcast/15736742995
[Nov 25 16:19:48] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=ALARMS))
[Nov 25 16:19:48] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Nov 25 16:19:48] -- Executing [815734315279@default:2] Dial("Local/815734315279@default-3736,2", "SIP/Xcast/15734315279||tTr") in new stack
[Nov 25 16:19:48] -- Called Xcast/15734315279
[Nov 25 16:19:48] WARNING[8140]: rtp.c:953 ast_rtcp_read: RTCP Read too short
[Nov 25 16:19:48] -- Got SIP response 503 "Carrier Service Unavailable" back from 38.102.250.60
[Nov 25 16:19:48] -- SIP/Xcast-00000372 is circuit-busy
[Nov 25 16:19:48] == Everyone is busy/congested at this time (1:0/1/0)
[Nov 25 16:19:48] -- Executing [815732213732@default:3] Hangup("Local/815732213732@default-1378,2", "") in new stack
[Nov 25 16:19:48] == Spawn extension (default, 815732213732, 3) exited non-zero on 'Local/815732213732@default-1378,2'