Topic: Help please

[groutley]

Hi Jeff,
  Thanks for that update.  (I think)
Wasn't sure whether to make the plunge into the beta 2.2 stuff, but I guess you talked me into it.

The good news is the SPA provisioning is looking good, the bad news is I can't receive or make any phone calls !
I think the main concern is 'Unable to open Asterisk database' messages..

Code: [Select]

  == Auto fallthrough, channel 'SIP/5010-094a35a8' status is 'UNKNOWN'
    -- Executing [h@internal:1] Hangup("SIP/5010-094a35a8", "") in new stack
  == Spawn extension (internal, h, 1) exited non-zero on 'SIP/5010-094a35a8'
[Oct 30 16:22:55] NOTICE[8123]: cdr.c:434 ast_cdr_free: CDR on channel 'SIP/5010-094a35a8' not posted
[Oct 30 16:23:07] NOTICE[4564]: chan_sip.c:14776 handle_request_subscribe: Got SUBSCRIBE for extension 5001@internal from 192.168.37.19, but there is no hint for that extension.
    -- Executing [5010@internal:1] AGI("SIP/5000-094b6848", "selintra|OutCluster|5010") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
    -- AGI Script selintra completed, returning 0
  == Auto fallthrough, channel 'SIP/5000-094b6848' status is 'UNKNOWN'
    -- Executing [h@internal:1] Hangup("SIP/5000-094b6848", "") in new stack
  == Spawn extension (internal, h, 1) exited non-zero on 'SIP/5000-094b6848'
[Oct 30 16:23:13] NOTICE[8127]: cdr.c:434 ast_cdr_free: CDR on channel 'SIP/5000-094b6848' not posted
[Oct 30 16:24:04] WARNING[4564]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 16:24:04] WARNING[4564]: db.c:66 dbinit: Unable to open Asterisk database
those Warning message just keep scrolling..

I can dial *56* from an extension and get told the xtn # correctly.
I cannot dial another extension, I cant dial out any Trunk, or recieve any incoming calls !!

I followed the Instructions on  http://www.selintra.com/docs/cgi-bin/view/Main/DocChapter03b
i.e. rpm -e selintra-sail
rpm -e smeserver-asterisk
rpm -e smeserver-asterisk-zappri-MPP

then after midifying my yum.conf ..
yum install zaptel --enablerepo=atrpms
yum install libpri  --enablerepo=atrpms
yum install asterisk --enablerepo=atrpms
yum install zaptel-kmdl-`uname -r` --enablerepo=atrpms
yum install asterisk-addons  --enablerepo=atrpms
then downloaded the sail-2.2.1-538.noarch.rpm and performed
yum localinstall sail-2.2.1-538.noarch.rpm --enablerepo=base

finally with the signal-event post-upgrade; signal-event reboot

Then I did a commit, from the Globals page, followed by a 'Probe' on the PCI devices, and another commit.

HELP   

[SARK devs]

HI

sounds something is getting left behind after the uninstall. 

what do you have in musiconhold.conf?
what does ls -l /var/lib/asterisk/agi-bin show?

Stop asterisk from the console with "stop now"
Start it with asterisk -vvvvc

at the console do "agi debug"

run an internal call and post the console log


Best

S

[groutley]

Thanks Jeff,

musiconhold.conf

Code: [Select]

[root@l1nuxsvr asterisk]# cat musiconhold.conf
;#------------------------------------------------------------
;# DO NOT MODIFY THIS FILE! It is updated automatically by the
;# SME Server software. Instead, modify the source template in
;# an /etc/e-smith/templates-custom directory. For more
;# information, see http://www.e-smith.org/custom/
;#
;# copyright (C) 2005 Selintra Ltd. United Kingdom
;#------------------------------------------------------------

[default]
mode=files
directory=>/var/lib/asterisk/moh
random=yes

ls -l /var/lib/asterisk/agi-bin

Code: [Select]

[root@l1nuxsvr asterisk]# ls -l /var/lib/asterisk/agi-bin
total 104
-rwxr-xr-x  1 asterisk asterisk  1742 Oct 10 15:12 agi-test.agi
-rwxr-xr-x  1 asterisk asterisk  7216 Oct 10 15:13 eagi-sphinx-test
-rwxr-xr-x  1 asterisk asterisk  6120 Oct 10 15:13 eagi-test
-rwxr-xr-x  1 asterisk asterisk 14530 Oct 10 15:12 jukebox.agi
-rwxr-xr-x  1 root     root     62200 Oct 30 10:04 selintra

Connected to Asterisk 1.4.12.1 currently running on l1nuxsvr (pid = 4425)
Verbosity is at least 7
l1nuxsvr*CLI> stop now
l1nuxsvr*CLI>
Disconnected from Asterisk server
[root@l1nuxsvr asterisk]#asterisk -vvvvc

Code: [Select]

AGI Debugging Enabled
*CLI>     -- Executing [5010@internal:1] AGI("SIP/5009-0a126180", "selintra|OutCluster|5010") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
AGI Tx >> agi_request: selintra
AGI Tx >> agi_channel: SIP/5009-0a126180
AGI Tx >> agi_language: en
AGI Tx >> agi_type: SIP
AGI Tx >> agi_uniqueid: 1193742185.0
AGI Tx >> agi_callerid: 5009
AGI Tx >> agi_calleridname: Glen
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: 5010
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: internal
AGI Tx >> agi_extension: 5010
AGI Tx >> agi_priority: 1
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
AGI Rx << SET PRIORITY 1
AGI Tx >> 200 result=0
AGI Rx << SET EXTENSION 5010
AGI Tx >> 200 result=0
AGI Rx << SET CONTEXT Home
AGI Tx >> 200 result=0
    -- AGI Script selintra completed, returning 0
    -- Executing [5010@Home:1] AGI("SIP/5009-0a126180", "selintra|InCall|") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
AGI Tx >> agi_request: selintra
AGI Tx >> agi_channel: SIP/5009-0a126180
AGI Tx >> agi_language: en
AGI Tx >> agi_type: SIP
AGI Tx >> agi_uniqueid: 1193742185.0
AGI Tx >> agi_callerid: 5009
AGI Tx >> agi_calleridname: Glen
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: 5010
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: Home
AGI Tx >> agi_extension: 5010
AGI Tx >> agi_priority: 1
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
AGI Rx << DATABASE GET "STAT" "OCSTAT"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "cfimopen" "5010"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "cfim" "5010"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "STAT" "OCSTAT"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "cfimopen" "5010"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "STAT" "OCSTAT"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "cfim" "5010"
AGI Tx >> 200 result=0
AGI Rx << DATABASE GET "ringdelay" "5010"
AGI Tx >> 200 result=0
AGI Rx << GET VARIABLE MOH
AGI Tx >> 200 result=0
AGI Rx << EXEC Dial SIP/5010|20|tTwW
    -- AGI Script Executing Application: (Dial) Options: (SIP/5010|20|tTwW)
    -- Called 5010
    -- SIP/5010-0a12b3f8 is ringing
[Oct 30 22:03:06] NOTICE[10112]: cdr.c:434 ast_cdr_free: CDR on channel 'SIP/5010-0a12b3f8' not posted
AGI Tx >> 200 result=-1
  == Spawn extension (Home, 5010, 1) exited non-zero on 'SIP/5009-0a126180'
    -- Executing [h@Home:1] Hangup("SIP/5009-0a126180", "") in new stack
  == Spawn extension (Home, h, 1) exited non-zero on 'SIP/5009-0a126180'

Hmm  it worked !
Very odd,  I have been out at my sons school presentation night,
the last thing I did before leaving was another "signal-event post-upgrade; signal-event reboot"

Seems that all is good again after that !..
I have not fully tested, as it is late and with phones ringing all around the house I won't be popular,
But I am happy for now.. 
Really appreciate your speedy diagnostic reply, but panic is off for now 

I'll play more tomorrow if I get a chance, although I may have to travel interstate with work yet.
Thanks again 
 G

[SARK devs]

Good oh.

No hurry, but we're looking forward to your verdict on our spa provisioning efforts.

Kind Regards

S

[groutley]

Hi Jeff,
  OK spoke too soon,
it gets wierder !
I did a 'signal-event reboot'  to cleanly restart and have sail brought up normaly.
and it doesn't work again !
Same issue,  cant ring extensions..  cant recieve incoming calls. etc..

So I stop asterisk again, and start with 'asterisk -vvvvc
and All works !!
I restart 'signal-event reboot' again, and Yes  It's broken again !!
When I call another extn

Code: [Select]

    -- Executing [10@internal:1] AGI("SIP/5009-096da858", "selintra|OutCluster|10") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
    -- AGI Script selintra completed, returning 0
  == Auto fallthrough, channel 'SIP/5009-096da858' status is 'UNKNOWN'
    -- Executing [h@internal:1] Hangup("SIP/5009-096da858", "") in new stack
  == Spawn extension (internal, h, 1) exited non-zero on 'SIP/5009-096da858'
[Oct 30 22:43:42] NOTICE[5215]: cdr.c:434 ast_cdr_free: CDR on channel 'SIP/5009-096da858' not posted

with an incoming PSTN call via the SPA-3102 trunk

Code: [Select]

    -- Executing [97951738@mainmenu:1] AGI("SIP/97951738-096da858", "selintra|Inbound|97951738") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
    -- AGI Script selintra completed, returning 0
  == Auto fallthrough, channel 'SIP/97951738-096da858' status is 'UNKNOWN'
    -- Executing [h@mainmenu:1] Hangup("SIP/97951738-096da858", "") in new stack
  == Spawn extension (mainmenu, h, 1) exited non-zero on 'SIP/97951738-096da858'
[Oct 30 22:44:14] NOTICE[5219]: cdr.c:434 ast_cdr_free: CDR on channel 'SIP/97951738-096da858' not posted

I am also in this state still getting...

Code: [Select]

[Oct 30 22:45:00] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:45:24] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:45:25] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:45:25] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:46:06] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:47:55] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:48:19] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:48:22] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:48:22] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database
[Oct 30 22:48:24] NOTICE[4120]: chan_sip.c:14776 handle_request_subscribe: Got SUBSCRIBE for extension 5001@internal from 192.168.37.19, but there is no hint for that extension.
[Oct 30 22:48:49] WARNING[4120]: db.c:66 dbinit: Unable to open Asterisk database

Any ideas ?
at least I have a work around

G

[groutley]

Update..
  if I 'stop now' asterisk
and then  ' /etc/init.d/sark start '
All works !

So is there a left over that is kicking off the previous version during SME boot ?

[groutley]

No hurry, but we're looking forward to your verdict on our spa provisioning efforts.

OK, I have played a bit now with the SPA-3102 provisioning,
while mucking around with the above wierd symptoms.

I Australianized the spa-3102fxo and fxs templates,
deleted and re-added both my SPA3102 extn and Trunk.
I am now able to put 'telstra call' into the PSTN Display name
and edit the current trunk provisioning
all the extra <CR> in the spa____.cfg have gone
even the <flat-profile>, </flat-profile>  appear only at the begining and end of the cfg
Wow what a great clean up !  looking really good.

Only one extra that I hadn't previously mentioned.
on the spa-3102 the 'Profile Rule' of /spa$MA.cfg
requests the spa_____.cfg from the tftp server as a Lowercase name.
So while I define each of the trunk and extension,
I type the MAC address of the SPA3102 with lower case alpha characters,
But when I go into the trunk or extension definition, the MAC has become uppercase,
so of course the SPA will not find the cfg file.
I can correct the defined trunk and extension so the MAC is lower case, and all works great.
but if I enter the MAC in lower case, it should not translate to upper,
I would expect the behaviour to be a direct entry.
i.e. If I entered lower case alpha characters in the MAC, then it should accept and keep the lower case,
If I type in Upper case, it should keep and Use the Uppercase characters as I entered.

If it worked like that I can't whinge, about SAIL transposing my characters,  I could only blame myself for poor typing

Otherwise I really think the 3102 provisioning is looking great !

G

[SARK devs]

Excellent....

re profile rule...

/spa$MA.cfg will cause the 3102 look for a lower case name file, /spa$MAU.cfg will cause it to look for uppercase.  You should be abe to set this in the spa3102.cfg descriptor so that it is picked up during two-phase cold boot or you can set it via the browser or just take whatever the unit defaults to (usually $MA).

In our keywords (at the beginning of the provisioning stream), "spa$MAC.cfg" will produce a tftp file with uppercase mac address and "spa$mac.cfg" will produce a tftp file with lowercase mac address.

So you have full control to change what the unit is looking for and what file name we will set.


For your problem at start up do...

config setprop asterisk status disabled

That should do it.

We'll fix this properly in a very near release.

Kind Regards

S

[SARK devs]

-540 should cure your strange start-up behaviour ills.

It's up on the ftp site now.

Kind Regards

S

[groutley]

Hi Jeff,
 I have installed -540 and appears to have corrected my statup blues  thanks ,
But I am now seeing some odd stuff in the Sark FOP.
No longer are my Trunks listed at all,
But there are some odd ones..
Telappliant1
astratel1
switchtiny
test65tiny

Also extns 5000 thru 5004 display, yet I dont have xtns 5001thru 5003 and my other extensions I do have are not displayed at all ?
I have a feeling something got broken in the SARK FOP build code, and a test config remains.
It is Beta code after all 
Glen

[SARK devs]

almost certainly broken.

I'll put it on the list.

Might get a fix out mon/tues.

Best and thanks

J

[SARK devs]

This is odd.  You are picking up the test data from the FOP .cfg file in the distribution.  It's almost as if you've never done a commit since you installed the new package.

Hmmm.

Do a commit and have a look in the logs for me (just the regular SME messages log)...

What you are looking for is this...(this is our reference 2.1.1/1.4 system)

Code: [Select]

Nov  2 15:27:13 switch esmith::event[16637]: Processing event: conf-fop 
Nov  2 15:27:13 switch esmith::event[16637]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Nov  2 15:27:14 switch esmith::event[16637]: expanding /usr/local/operator/op_buttons.cfg 
Nov  2 15:27:14 switch esmith::event[16637]: expanding /usr/local/operator/op_server.cfg 
Nov  2 15:27:15 switch esmith::event[16637]: generic_template_expand=action|Event|conf-fop|Action|generic_template_expand|Start|1194017233 912607|End|1194017235 74048|Elapsed|1.161441
Nov  2 15:27:15 switch esmith::event[16637]: Running event handler: /etc/e-smith/events/conf-fop/S55conf-fop
Nov  2 15:27:16 switch fop: op_server.pl shutdown succeeded
Nov  2 15:27:16 switch esmith::event[16637]: Shutting down Flash Operator Panel: [  OK  ]
 
Nov  2 15:27:17 switch fop: op_server.pl startup succeeded
Nov  2 15:27:17 switch esmith::event[16637]: Starting Flash Operator Panel: [  OK  ]

See if you are getting any failures in this sequence

Best

S

[groutley]

Hi Jeff,
  curious, about to do a commit, (which possibly was an oversight on my part),
I go into Global Settings and at the top of the panel its says
"Version: sail-2.2.1-538 sail-2.2.1-540 " 

So now I do the commit..

Code: [Select]

Nov  3 08:26:08 l1nuxsvr esmith::event[2788]: Processing event: conf-fop
Nov  3 08:26:08 l1nuxsvr esmith::event[2788]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Nov  3 08:26:09 l1nuxsvr esmith::event[2788]: expanding /usr/local/operator/op_server.cfg
Nov  3 08:26:09 l1nuxsvr esmith::event[2788]: expanding /usr/local/operator/op_buttons.cfg
Nov  3 08:26:09 l1nuxsvr esmith::event[2788]: generic_template_expand=action|Event|conf-fop|Action|generic_template_expand|Start|1194038768 760037|End|1194038769 672080|Elapsed|0.912043
Nov  3 08:26:09 l1nuxsvr esmith::event[2788]: Running event handler: /etc/e-smith/events/conf-fop/S55conf-fop
Nov  3 08:26:09 l1nuxsvr fop: op_server.pl shutdown succeeded
  v  3 08:26:10 l1nuxsvr esmith::event[2788]: Shutting down Flash Operator Panel: [  OK  ]
Nov  3 08:26:10 l1nuxsvr fop: op_server.pl startup succeeded
  v  3 08:26:10 l1nuxsvr esmith::event[2788]: Starting Flash Operator Panel: [  OK  ]
Nov  3 08:26:10 l1nuxsvr esmith::event[2788]: S55conf-fop=action|Event|conf-fop|Action|S55conf-fop|Start|1194038769 672911|End|1194038770 832668|Elapsed|1.159757
Nov  3 08:26:10 l1nuxsvr esmith::event[2762]: S55conf-asterisk=action|Event|conf-asterisk|Action|S55conf-asterisk|Start|1194038767 929653|End|1194038770 845070|Elapsed|2.915417
Nov  3 08:26:10 l1nuxsvr /etc/e-smith/web/panels/manager/cgi-bin/sarkglobals[2524]: /home/e-smith/db/selintra-work: OLD global=globals|ADDHEADER|NO|AGENTSTART|1001|ALERT|None|CALLRECORD1|One-Touch|CALLRECORD2|None|CDR|YES|CFEXTRN|ON|COMMIT|NO|COMPRESSION|THRUPUT|CONFTYPE|simple|COUNTRY|au|DIGITS|2|DIGIUMCARD|NO|DISAPASSWORD||EDOMAIN||EMAILALERT|glen@routley.homeip.net|FAX|5003|FAXDETECT|3|FOPPASS|1234|FORMAT-2.1.11|YES|G729||INTRINGDELAY|20|LCLVOIPMAX|10|LOCALIP|192.168.37.251|LOGBAK||LOGOPTS|native|LTERM|NO|MAILMODE|automatic|MEETMEDIAL|_30[0-7]|ONETOUCHREC|NO|OPERATOR|0|PLAYBEEP|NO|PLAYBUSY|NO|PLAYCONGESTED|NO|PROXY|NO|PROXYIGNORE||RINGDELAY|0|SIPIAXSTART|5000|SMSALERT||SMSC||SPYPASS|4444|SUBNET|192.168.37.0|SUPEMAIL|admin@routley.homeip.net|SYSOP|5010|SYSPASS|1111|TFTP|YES|TIMEOUTD|5|TIMEOUTR|10|UNDO|YES|UNDONUM|3|VLIBS|/var/log /var/lib/mysql /var/lib/dhcp /var/lock /var/qmail /var/spool /var/tmp /tmp /tftpboot|VMAILSERVER||VOICEINSTR|YES|VOIPMAX|3
Nov  3 08:26:10 l1nuxsvr /etc/e-smith/web/panels/manager/cgi-bin/sarkglobals[2524]: /home/e-smith/db/selintra-work: NEW global=globals|ADDHEADER|NO|AGENTSTART|1001|ALERT|None|CALLRECORD1|One-Touch|CALLRECORD2|None|CDR|YES|CFEXTRN|ON|COMMIT|NO|COMPRESSION|THRUPUT|CONFTYPE|simple|COUNTRY|au|DIGITS|2|DIGIUMCARD|NO|DISAPASSWORD||EDOMAIN||EMAILALERT|glen@routley.homeip.net|FAX|5003|FAXDETECT|3|FOPPASS|1234|FORMAT-2.1.11|YES|G729||INTRINGDELAY|20|LCLVOIPMAX|10|LOCALIP|192.168.37.251|LOGBAK||LOGOPTS|native|LTERM|NO|MAILMODE|automatic|MEETMEDIAL|_30[0-7]|ONETOUCHREC|NO|OPERATOR|0|PLAYBEEP|NO|PLAYBUSY|NO|PLAYCONGESTED|NO|PROXY|NO|PROXYIGNORE||RINGDELAY|0|SIPIAXSTART|5000|SMSALERT||SMSC||SPYPASS|4444|SUBNET|192.168.37.0|SUPEMAIL|admin@routley.homeip.net|SYSOP|5010|SYSPASS|1111|TFTP|YES|TIMEOUTD|5|TIMEOUTR|10|UNDO|YES|UNDONUM|4|VLIBS|/var/log /var/lib/mysql /var/lib/dhcp /var/lock /var/qmail /var/spool /var/tmp /tmp /tftpboot|VMAILSERVER||VOICEINSTR|YES|VOIPMAX|3


Global Panel says..  "Operation status report -  Globals saved"
and still at the top "Version: sail-2.2.1-538 sail-2.2.1-540"
But hey,  my SARK fop panel is back to normal.
So I guess I have been a very naughty boy !  although the Version display does seem odd.

Now I am curious, in the test.cfg display I saw, obviously it was slightly customised.
My Trunks just display 'Trunk 1', 'Trunk 2' etc.. 
Can I customise these to actually disply the trunk name ?
Also When I look at the SARK web site it displays a VERY different looking FOP,
is this a SARK upgrade required, or is that achievable in a conf file ?

[SARK devs]

SARK FOP trunks use a cute self-defining technique which means they act like a pool and will reflect incoming SIP and IAX calls as they arrive.  Most (all?) other implementations  we've seen use a fixed number of trunks for each channel type (ZAP, SIP, IAX).  This is quite inflexible and you have to guess in advance how many of each are required.

Can you change it?  Weeell, possibly.  SARK synthesises the layout using SME templates.  Best we could do is to give you a switch to turn off SARK processing altogether and then you can do whatever you wish.  The buttons.cfg file which FOP uses isn't difficult to hand build. You can view SARK's efforts at /usr/local/operator/op_buttons.cfg.

Might not be a bad idea, we can put it on the build list.

As to your strange output for the version.  Never seen that happen before.  We don't keep the version anywhere in SARK itself, we simply query rpm with "rpm -q sail" and then print the output.  Never seen rpm -q give two versions.  What's that all about?



Best

S

 

[groutley]

Hi Jeff,
 your explanation on the fop makes sense to me,  I had been looking at the 0.27 release of fop and the demo panels, and liked the look,  but your trunk implementation certainly makes sense, and I would be silly to change that clever implementation.
Anyway,  I seem to be having another problem, 
I have a couple of different VSP's each with different DID's,
currently when ringing the DID, it gets to SAIL/Asterisk,  but never rings an extension
here is an agi debug of an incoming call..

Code: [Select]

    -- Executing [s@mainmenu:1] AGI("SIP/61386835551-09fefcb0", "selintra|CheckState|") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/selintra
AGI Tx >> agi_request: selintra
AGI Tx >> agi_channel: SIP/61386835551-09fefcb0
AGI Tx >> agi_language: en
AGI Tx >> agi_type: SIP
AGI Tx >> agi_uniqueid: 1194260114.0
AGI Tx >> agi_callerid: 0396270000
AGI Tx >> agi_calleridname: unknown
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: mainmenu
AGI Tx >> agi_extension: s
AGI Tx >> agi_priority: 1
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
AGI Rx << DATABASE GET "STAT" "OCSTAT"
AGI Tx >> 200 result=0
AGI Rx << SET VARIABLE REMOTENUM ""
AGI Tx >> 200 result=1
AGI Rx << SET VARIABLE OPEN "YES"
AGI Tx >> 200 result=1
AGI Rx << DATABASE GET "STAT" "IVRSTAT"
AGI Tx >> 200 result=0
AGI Rx << SET PRIORITY 1
AGI Tx >> 200 result=0
AGI Rx << SET EXTENSION
AGI Tx >> 520-Invalid command syntax.  Proper usage follows:
AGI Tx >>  Usage: SET EXTENSION <new extension>
        Changes the extension for continuation upon exiting the application.
AGI Tx >> 520 End of proper usage.
AGI Rx << SET CONTEXT extensions
AGI Tx >> 200 result=0
    -- AGI Script selintra completed, returning 0
    -- Sent into invalid extension 's' in context 'extensions' on SIP/61386835551-09fefcb0
    -- Executing [i@extensions:1] PlayTones("SIP/61386835551-09fefcb0", "congestion") in new stack
  == Auto fallthrough, channel 'SIP/61386835551-09fefcb0' status is 'UNKNOWN'
    -- Executing [h@extensions:1] Hangup("SIP/61386835551-09fefcb0", "") in new stack
  == Spawn extension (extensions, h, 1) exited non-zero on 'SIP/61386835551-09fefcb0'
[Nov  5 21:55:14] NOTICE[16239]: cdr.c:434 ast_cdr_free: CDR on channel 'SIP/61386835551-09fefcb0' not posted

Eventually I get a reorder tone on the phone I dialled from, as the call never connects, not even to voicemail.
Like I said this is ocurring with 2 different VSP's and I have tried changing the Inbound Route to various extensions with the same result.
All extensions and dial out thru these Trunks works fine.

G