The problem was with the way NAT was configured. My server has 2 cards - I was using the external ip for all config (including the database). But my SIP clients were using the internal ips on my LAN. The moment I changed the ip of the server using the internal ip - it worked.
So looks like a silly problem - but I assumed the external ip was being discussed because of the examples given in the scratch_install doc.
Now I have another problem. Seems to be more asterisk related. I get the error message when vicidial dials out saying "an error has occurred, contact tech support".
I notice that asterisk console messages reveal a context called Local/xxxxx@default
The console messages are
Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing Conference("Local/8600051@default-634b,2", "8600051") in new stack
Jul 15 23:09:45 NOTICE[8948]: member.c:415 member_exec: [ $Revision: 1.9 $ ] begin processing member thread, channel => Local/8600051@default-634b,2
> Channel Local/8600051@default-634b,1 was answered.
-- Executing Dial("Local/8600051@default-634b,1", "SIP/inphonex-out/11234567890|55|o") in new stack
Jul 15 23:09:45 NOTICE[8948]: member.c:742 create_member: attempting to parse passed params, stringp => 8600051
Jul 15 23:09:45 NOTICE[8948]: member.c:793 create_member: parsed data params, id => 8600051, flags => , priority => 0, vad_prob_start => 0.050000, vad_prob_continue => 0.020000
Jul 15 23:09:45 NOTICE[8948]: member.c:1077 create_member: created member, type => S, priority => 0, readformat => 64
-- Called inphonex-out/16093570153
Jul 15 23:09:45 NOTICE[8948]: member.c:451 member_exec: CHANNEL INFO, CHANNEL => Local/8600051@default-634b,2, DNID => (null), CALLER_ID => 1234567890, ANI => 1234567890
Jul 15 23:09:45 NOTICE[8948]: member.c:454 member_exec: CHANNEL CODECS, CHANNEL => Local/8600051@default-634b,2, NATIVE => 64, READ => 64, WRITE => 64
Jul 15 23:09:45 NOTICE[8948]: conference.c:504 start_conference: attempting to find requested conference
Jul 15 23:09:45 NOTICE[8948]: conference.c:563 find_conf: found conference in conflist, name => 8600051
Jul 15 23:09:45 NOTICE[8948]: conference.c:796 add_member: member added to conference, name => 8600051
Jul 15 23:09:45 NOTICE[8948]: member.c:514 member_exec: begin member event loop, channel => Local/8600051@default-634b,2
Jul 15 23:09:45 NOTICE[8948]: member.c:532 member_exec: Conference Members: 2
Jul 15 23:09:45 NOTICE[8948]: member.c:538 member_exec: Quiet debug 0 - 0
Jul 15 23:09:45 NOTICE[8948]: member.c:688 basic_play_sound: playing conference message enter
Jul 15 23:09:45 NOTICE[8948]: member.c:688 basic_play_sound: playing conference message enter
Jul 15 23:09:45 NOTICE[8948]: member.c:351 process_outgoing: unanticipated delivery time, delivery_diff => -889788173, delivery.tv_usec => 523500
-- SIP/inphonex-out-007e6510 answered Local/8600051@default-634b,1
== Manager 'sendcron' logged off from 127.0.0.1
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
== Manager 'sendcron' logged off from 127.0.0.1
== Manager 'sendcron' logged off from 127.0.0.1
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
== Spawn extension (default, 11234567890, 1) exited non-zero on 'Local/8600051@default-634b,1'
-- Executing DeadAGI("Local/8600051@default-634b,1", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script
agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing DeadAGI("Local/8600051@default-634b,1", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----18)") in new stack
-- AGI Script
agi://127.0.0.1:4577/VD_hangup--HVcause ... -20-----18) completed, returning 0
Jul 15 23:10:05 NOTICE[8948]: member.c:612 member_exec: unable to read from channel, channel => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8948]: member.c:635 member_exec: end member event loop, time_entered => 1184521185
== Spawn extension (default, 8600051, 1) exited non-zero on 'Local/8600051@default-634b,2'
-- Executing DeadAGI("Local/8600051@default-634b,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script
agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing DeadAGI("Local/8600051@default-634b,2", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------)") in new stack
-- AGI Script
agi://127.0.0.1:4577/VD_hangup--HVcause ... ----------) completed, returning 0
Jul 15 23:10:05 ERROR[8714]: conference.c:216 conference_exec: skipping leave message on Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: member.c:688 basic_play_sound: playing conference message leave
Jul 15 23:10:05 NOTICE[8714]: conference.c:234 conference_exec: found member slated for removal, channel => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: conference.c:844 remove_member: member accounting, channel => Local/8600051@default-634b,2, te => 1184521185, fi => 891, fid => 53, fo => 1003, fod => 0, tt => 20
Jul 15 23:10:05 NOTICE[8714]: member.c:1104 delete_member: freeing member flags, name => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: member.c:1116 delete_member: deleting member input frames, name => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: member.c:1130 delete_member: deleting member output frames, name => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: member.c:1156 delete_member: freeing member translator paths, name => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: member.c:1168 delete_member: freeing member channel name, name => Local/8600051@default-634b,2
Jul 15 23:10:05 NOTICE[8714]: member.c:1175 delete_member: freeing member
Jul 15 23:10:05 NOTICE[8714]: conference.c:869 remove_member: removed member from conference, name => 8600051, remaining => 1
== Manager 'sendcron' logged off from 127.0.0.1
My extensions has a context called local that only has these lines:
[local]
ignorepat => 9
include => default
The default context has all the details as per the scratch_install doc.
I am able to make a normal SIP call out without using vicidial. Any ideas why this error when vicidial dials out?
thanks in advance,