Hi, Bogdan.
I have found the exact situation and function that cause the problem:
it is the uac_replace_from() function when called by an INVITE
retrasmission!
I discovered this because I found that I can avoid the problem simply
testing if an INVITE is a retrasmission (with the t_lookup_request()
function) and in this case immediatly execute an "exit". In this way
the AVPs no longer disapper from the accounting.
If I position this test immediately before the call to
uac_replace_from(), then the AVPs are still present. If I position
this test immediately after the call to uac_replace_from(), then the
AVPs disappear from the accounting!
Now, I think that you should intervene because I think that I don't
have the "know how" to fully understand the internal functioning of
the uac_replace_from()...
Tell me if you need any more information.
Bye.
Federico Giannici wrote:
Bogdan-Andrei Iancu wrote:
Hi Frederico,
it;s not sure for me what the scenario is: you have calls to PSTN
which hits failure_route in order to perform auth and from mangling
via UAC module, right?
Yes.
Actually, the from mangling is done in the route, the auth mangling
in the failure_route.
and if there are retransmissions of the INVITE
the AVPs disappear -
where exactly on the path?
They are still there at the end of the route (after the final
t_relay), before and after the corresponding onreply_route and
failure_route.
But they are not there when the accounting is done.
can pin point? and if there are no
retransmissions, everything is ok?
Yes, with no retrasmission I never found that they disappear.
Moreover I was able to reproduce the problem only when the UAC module
is used.
by the way we are talking about ACK or INVITE?
I'm talking about the INVITE.
I'm not an expert of SER internals, but I have a suggestion: could it
be related to the way the uac_auth() function (in modules/uac/auth.c)
chooses the branch to use when there is a retrasmit?
Thanks.
> Federico Giannici wrote:
>
>> The problem is surely related to the retrasmission of the INVITEs
>> and probably to the UAC module.
>>
>> I was able to reproduce the problem (AVPs vanishing) by adding a
>> delay of 3 seconds for every message received. In this way a caused
>> a couple of retrasmission for each message. Moreover that occurred
>> only to the calls to a PSTN gateway using by the UAC module for
>> authenticatuion and From substitution.
>>
>> In this case the INVITE's AVPs are no more present when the INVITE
>> is logged by the ACC module (even if they were present before
>> entering the transaction engine).
>>
>> If calls are made to other voip users (not using the UAC module) or
>> if there is non INVITE retrasmission, the AVPs are correctly found.
>>
>> BTW, I used the t_lookup_request() function to test if the message
>> is a retrasmission. It turned out that that INVITE and BYE
>> retrasmissions are correctly identified, but not the ACK
>> retrasmission. I don't know if this is the correct behaviour.
>>
>> I hope this information can be useful to find the problem.
>>
>> Thanks.
>>
>>
>>
>> Federico Giannici wrote:
>>
>>> The mistery keeps increasing!
>>>
>>> I have found that if I set an AVP in an INVITE message, then the
>>> same AVP is logged in the ACCOUNTING of the corresponding ACK
>>> message!
>>>
>>> But it is NOT in the ACK message before it enters the transaction
>>> engine (with a t_relay()). So it is the transaction engine that
>>> "copies" the INVITE AVPS to the ACK. Or the accounting routines
>>> use the AVPs from the corresponding INVITE instead of the ACK ones.
>>>
>>> Is this the expected behaviour?
>>> Why this happens?
>>>
>>> Thanks.
>>>
>>>
>>>
>>> Federico Giannici wrote:
>>>
>>>> I'm still not able to make these errors reproducible.
>>>>
>>>> Now I found that the "AVPs vanishing" occours with 200 OK
>>>> situations too. It seems to be related to packets retransmission.
>>>> They occur when the following errors are logged:
>>>>
>>>> Nov 28 22:28:30 eowyn OpenSER[3010]: ERROR: t_newtran:
>>>> transaction already in process 0x502bbc58
>>>> Nov 28 22:28:30 eowyn OpenSER[3010]: ERROR: sl_reply_error used:
>>>> I'm terribly sorry, server error occurred (1/SL)
>>>>
>>>> These are generated by this classic code:
>>>>
>>>> if ( !t_relay() )
>>>> {
>>>> sl_reply_error();
>>>> }
>>>>
>>>> Now I'm asking myself: is it correct to reply to a message that
>>>> is a retrasmission? Shouldn't we simply ignore it? Couldn't it
>>>> confuse the UAs?
>>>>
>>>> Thanks.
>>>>
>>>>
>>>>
>>>> Federico Giannici wrote:
>>>>
>>>>> Bogdan-Andrei Iancu wrote:
>>>>>
>>>>>> Hi Federico,
>>>>>>
>>>>>> use avp_print() (works only with debug=9) in failure_route to
>>>>>> inspect the list of present AVP. maybe you do not have the AVPs
>>>>>> you are trying to log.
>>>>>
>>>>>
>>>>>
>>>>>
>>>>>
>>>>>
>>>>>
>>>>>
>>>>> But I set those AVPs in EVERY message received by the server.
>>>>> Moreover, I'm SURE they are there because before forwarding
>>>>> those INVITEs with t_relay() I log the messages to syslog and
>>>>> the AVPs ARE THERE.
>>>>>
>>>>> Then, when some kind of errors are received (488, 422, etc.) it
>>>>> seems that those AVPs are "lost" by the transaction
engine...
>>>>> But I'm not sure what conditions cause this lost.
>>>>>
>>>>> Bye.
>>>>>
>>>>>
>>>>>> Federico Giannici wrote:
>>>>>>
>>>>>>> Daniel-Constantin Mierla wrote:
>>>>>>>
>>>>>>>> Hello,
>>>>>>>>
>>>>>>>> have you set the flag to log missed transaction?
>>>>>>>>
http://openser.org/docs/modules/1.0.x/acc.html#AEN407
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> No, I set the following:
>>>>>>>
>>>>>>> modparam("acc", "db_flag", 1)
>>>>>>> modparam("acc",
"failed_transaction_flag", 1)
>>>>>>>
>>>>>>> But no "db_missed_flag".
>>>>>>> Anyway:
>>>>>>>
>>>>>>> 1) I don't want to log missed calls in a separate table.
>>>>>>>
>>>>>>> 2) The failed INVITEs are actually logged in the normal
table,
>>>>>>> but the AVPs I set are not logged (it seems that they are not
>>>>>>> found).
>>>>>>>
>>>>>>> In normal cases the AVP are correctly logged. Even in many
>>>>>>> error cases (404, and so on) they are logged too. But in some
>>>>>>> cases, with strange errors (488, 422), the AVPs are NOT
logged
>>>>>>> (accounting is done, but AVPs are "n\a")!
>>>>>>>
>>>>>>> Any explanation of this?
>>>>>>>
>>>>>>> Thanks.
>>>>>>>
>>>>>>>
>>>>>>>> Or is that the some avps are not any more stored for
failed
>>>>>>>> transaction? Maybe some snippets of your config will give
us
>>>>>>>> more hints about what happens there.
>>>>>>>>
>>>>>>>> Cheers,
>>>>>>>> Daniel
>>>>>>>>
>>>>>>>> On 11/26/05 14:04, Federico Giannici wrote:
>>>>>>>>
>>>>>>>>> I'm using OpenSER 1.0.0 on OpenBSD 3.7 amd64.
>>>>>>>>>
>>>>>>>>> I have a strange problem with the accounting: I set a
couple
>>>>>>>>> of AVPs for every message that arrives at the server.
I'm
>>>>>>>>> sure they are there because they are written in the
syslog
>>>>>>>>> logging. Sometimes, when an INVITE is relayed (with
>>>>>>>>> transactions) and receives an error (488, 422, etc.),
in the
>>>>>>>>> SQL logging there is no more presence of the AVPs!
>>>>>>>>> Is this a known problem?
>>>>>>>>> How can I avoid this?
>>>>>>>>>
>>>>>>>>>
>>>>>>>>> Thanks.
>>>>>>>>