Hi Tony,

See my answers inline.


On 05/06/2014 12:11 PM, Tony Mountifield wrote:
In article <[email protected]>,
Daniel McFarlane <[email protected]> wrote:
Hi All,

I've been working with Asterisk for 2.5 years now but I am new to the
mailing list.  Hoping to get some answers here..

I've written a piece of software to control Asterisk via AMI.  I'm able
to login, making outbound calls, receive/record calls, handle digits,
put people into conferences, etc.  Everything seems to work well, at a
low to medium call volume.
It would be worthwhile mentioning which version of Asterisk you are using.
As I added in my last email: I'm running Asterisk 11.2.1

I've been stress testing the system and found what seems to be a memory
issue within Asterisk.  However, after over a day of searching I can't
even find any bug reports or anything to bring me closer to a
solution..but still hoping to find a solution or that someone can help
me find one..

I connect to the AMI successfully.  In my test I am making an outbound
call through a sip "device" I configured within sip.conf. This sip
"device" basically connects back into the same Asterisk I am using to
generate the calls.

If I generate outbound calls (i.e.: Via Action: Originate) using a low
call volume everything seems to be fine.  e.g.: I can generate 10 calls
and they all go through and complete successfully (with a total of 20
calls bring processed by the same Asterisk, due to both outbound and
inbound calls).

The problem arises If I bombard (i.e.: Write all the Originate requests
within about 7 seconds) the AMI to generate 97 outbound calls (for a
total of 194 channels).  The first Originate commands seem to get
processed,  but then all of a sudden (after Asterisk seems to have
started processing a good amount of my Originate requests already)
Asterisk seems to reset the AMI interface! Without even having a dropped
connection I receive a new "Asterisk Call Manager/1.3" string and then
previous commands that I issued start coming back with Response: Error
and Message: Permission denied.

I've enabled debugging and here's the security log output when I connect:

[Apr  1 12:43:25] SECURITY[9877] res_security_log.c:
SecurityEvent="SuccessfulAuth",EventTV="1396370605-551714",Severity="Informational",Service="AMI",EventVersion="1",AccountID="username",SessionID="0x7f0c540d96b8",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/192.168.1.62/52139",UsingPassword="0",SessionTV="1396370605-551710"

(This is the time when I am issuing commands, it is accepting and
processing them)

Then all of a sudden (when I receive a new "Asterisk Call Manager/1.3"
string and commands are rejected with "Permission denied") here is what
the log shows:

[Apr  1 12:44:36] SECURITY[9877] res_security_log.c:
SecurityEvent="RequestNotAllowed",EventTV="1396370676-876795",Severity="Error",Service="AMI",EventVersion="1",AccountID="",SessionID="0x7f0c54072968",LocalAddress="IPV4/TCP/0.0.0.0/5038",RemoteAddress="IPV4/TCP/192.168.1.62/52144",RequestType="Action:
Originate",SessionTV="0-0"

(Note: How the AccountID is now empty)

At this same time the CLI shows:

    == Manager '*****' logged off from 192.168.1.**
Why ***-out the username and part of the IP address when you've included
them both in the log messages above?
Guess that was a slip. I did actually change my username to "username", but missed the IP address. No big deal anyways, as it's local :)

..yet it wasn't my application that disconnected and/or issued an
Action: logoff to Asterisk (my application doesn't even detect a
connection drop, so it seems like Asterisk just logs the manager user out!).

Why did Asterisk all of a sudden reset itself and/or lose reference to
the user I am authenticating with?
Just to be certain what is happening on the connection, set up a tcpdump
on the Asterisk box to capture the traffic into a file, then look at it
in wireshark. I find it easiest to create a suitable script:

root# cat capture-ami.sh
#!/bin/sh

DATE=`date '+%Y%m%d-%H%M%S'`
FILE=ami-`hostname -s`-$DATE.pkt

cd /var/tmp

tcpdump -C 8 -i any -n -p -s 0 -w $FILE tcp port 5038 </dev/null >/dev/null 2>&1 
&
root# ./capture-ami.sh

Then when you have reproduced the problem, kill the tcpdump and copy the file
to a machine that has wireshark. You can then see exactly what is happening
at the time of the problem - whether it is initiated by your client program
doing something strange or by Asterisk.

Cheers
Tony


Thank you for the in-dept usage information for TCP dump and your suggested script, btw.

I have investigated with Wireshark, reproduced the problem and confirmed that Asterisk is resetting the connection. I see a [RST, ACK] being issued from the Asterisk AMI port to my software. My software then re-initializes the connection with a [SYN].

I'm new to the mailing list so how do we proceed to find a working solution from here?

Thank You,

Daniel



--
_____________________________________________________________________
-- Bandwidth and Colocation Provided by http://www.api-digital.com --

asterisk-dev mailing list
To UNSUBSCRIBE or update options visit:
  http://lists.digium.com/mailman/listinfo/asterisk-dev

Reply via email to