[Spce-user] R: Log Error
Daniel Grotti
dgrotti at sipwise.com
Wed May 7 03:19:29 EDT 2014
Hi Alessandro,
the problem should be located here:
if (is_present_hf("Contact"))
{
$var(expires_param) = @contact.expires;
}
in /etc/kamailio/proxy/registrar.cfg
Probably some Contact doesn't contain Expires parameter, so the var is
not set.
This ERROR should be fixed some months ago.
Can you double check on your /etc/kamailio/proxy/registrar.cfg line 198 ?
Daniel
On 05/06/2014 07:45 PM, Alessandro Romano - Rp engineering wrote:
> Thanks for the reply!
>
> there is a way to identify this contact ? or to view the register message ?
>
> BR
>
> -----Messaggio originale-----
> Da: Daniel Grotti [mailto:dgrotti at sipwise.com]
> Inviato: martedì 6 maggio 2014 19.15
> A: Alessandro Romano - Rp engineering
> Cc: spce-user at lists.sipwise.com
> Oggetto: Re: [Spce-user] Log Error
>
> I think it is related to a particular contact inside Register message.
>
> Daniel
>
> On 6 May 2014 19:04, Alessandro Romano - Rp engineering <alessandro.romano at rpeng.it> wrote:
>>
>> Hi Daniel,
>> it appear randomly.
>>
>> i've attached other logs.
>>
>> BR
>>
>> May 6 18:52:44 31-7-144-140 proxy[4165]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:53:23 31-7-144-140 proxy[4168]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358108 at voip.positivonet.it
>> T=sip:0899358108 at voip.positivonet.it IP=91.214.73.242:5060
>> (127.0.0.1:5060) ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:53:23 31-7-144-140 proxy[4168]: NOTICE: <script>:
>> Authentication failed, stale nonce - R=sip:voip.positivonet.it
>> ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:53:23 31-7-144-140 proxy[4161]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358108 at voip.positivonet.it
>> T=sip:0899358108 at voip.positivonet.it IP=91.214.73.242:5060
>> (127.0.0.1:5060) ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:53:23 31-7-144-140 proxy[4161]: ERROR: <core>
>> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May 6
>> 18:53:23 31-7-144-140 proxy[4161]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:53:23 31-7-144-140 proxy[4161]: NOTICE: <script>: Contacts
>> successfully updated, expires in 300s - R=sip:voip.positivonet.it
>> ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:54:47 31-7-144-140 proxy[4161]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358060 at voip.positivonet.it
>> T=sip:0899358060 at voip.positivonet.it IP=31.7.147.23:5060
>> (127.0.0.1:5060) ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:54:47 31-7-144-140 proxy[4161]: NOTICE: <script>:
>> Authentication failed, no credentials - R=sip:voip.positivonet.it
>> ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:54:47 31-7-144-140 proxy[4165]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358060 at voip.positivonet.it
>> T=sip:0899358060 at voip.positivonet.it IP=31.7.147.23:5060
>> (127.0.0.1:5060) ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:54:47 31-7-144-140 proxy[4165]: ERROR: <core>
>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>> character, ; expected May 6 18:54:47 31-7-144-140 proxy[4165]: ERROR:
>> <core> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May
>> 6 18:54:47 31-7-144-140 proxy[4165]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:54:47 31-7-144-140 proxy[4165]: NOTICE: <script>: Contacts
>> successfully updated, expires in 300s - R=sip:voip.positivonet.it
>> ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:55:01 31-7-144-140 proxy[4164]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358195 at voip.positivonet.it
>> T=sip:0899358195 at voip.positivonet.it IP=91.214.72.41:60108
>> (127.0.0.1:5060) ID=feaa68530719-q2x7dl99fbnq May 6 18:55:01
>> 31-7-144-140 proxy[4164]: NOTICE: <script>: Authentication failed, no
>> credentials - R=sip:voip.positivonet.it ID=feaa68530719-q2x7dl99fbnq
>> May 6 18:55:01 31-7-144-140 proxy[4170]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358195 at voip.positivonet.it
>> T=sip:0899358195 at voip.positivonet.it IP=91.214.72.41:60108
>> (127.0.0.1:5060) ID=feaa68530719-q2x7dl99fbnq May 6 18:55:01
>> 31-7-144-140 proxy[4170]: NOTICE: <script>: Contacts successfully
>> updated, expires in 3600s - R=sip:voip.positivonet.it
>> ID=feaa68530719-q2x7dl99fbnq May 6 18:55:03 31-7-144-140 proxy[4163]:
>> NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358199 at voip.positivonet.it
>> T=sip:0899358199 at voip.positivonet.it IP=31.7.150.127:5060
>> (127.0.0.1:5060) ID=b010c356-93186469 at 192.168.1.100
>> May 6 18:55:03 31-7-144-140 proxy[4163]: NOTICE: <script>:
>> Authentication failed, stale nonce - R=sip:voip.positivonet.it
>> ID=b010c356-93186469 at 192.168.1.100
>> May 6 18:55:03 31-7-144-140 proxy[4168]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358199 at voip.positivonet.it
>> T=sip:0899358199 at voip.positivonet.it IP=31.7.150.127:5060
>> (127.0.0.1:5060) ID=b010c356-93186469 at 192.168.1.100
>> May 6 18:55:03 31-7-144-140 proxy[4168]: ERROR: <core>
>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>> character, ; expected May 6 18:55:03 31-7-144-140 proxy[4168]: ERROR:
>> <core> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May
>> 6 18:55:03 31-7-144-140 proxy[4168]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:55:03 31-7-144-140 proxy[4168]: NOTICE: <script>: Contacts
>> successfully updated, expires in 180s - R=sip:voip.positivonet.it
>> ID=b010c356-93186469 at 192.168.1.100
>> May 6 18:55:26 31-7-144-140 proxy[4164]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358192 at voip.positivonet.it:5060 T=sip:0899358192 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 18:55:26 31-7-144-140 proxy[4164]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 18:55:27 31-7-144-140 proxy[4170]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358193 at voip.positivonet.it:5060 T=sip:0899358193 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:55:27 31-7-144-140 proxy[4170]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:56:20 31-7-144-140 proxy[4168]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358108 at voip.positivonet.it
>> T=sip:0899358108 at voip.positivonet.it IP=91.214.73.242:5060
>> (127.0.0.1:5060) ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:56:20 31-7-144-140 proxy[4168]: ERROR: <core>
>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>> character, ; expected May 6 18:56:20 31-7-144-140 proxy[4168]: ERROR:
>> <core> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May
>> 6 18:56:20 31-7-144-140 proxy[4168]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:56:20 31-7-144-140 proxy[4168]: NOTICE: <script>: Contacts
>> successfully updated, expires in 180s - R=sip:voip.positivonet.it
>> ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:56:26 31-7-144-140 proxy[4165]: NOTICE: <script>: New
>> request - M=SUBSCRIBE R=sip:127.0.0.1:5062
>> F=sip:0899358190 at voip.positivonet.it
>> T=sip:0899358190 at voip.positivonet.it IP=91.214.72.41:45250
>> (127.0.0.1:5060) ID=MTgwM2ZkOWZmMjJmMGRjZGQ0NDMwYzNhOThiYzlhOWY
>> May 6 18:56:26 31-7-144-140 proxy[4165]: NOTICE: <script>: Handling
>> subscribe request for event 'message-summary' - R=sip:127.0.0.1:5062
>> ID=MTgwM2ZkOWZmMjJmMGRjZGQ0NDMwYzNhOThiYzlhOWY
>> May 6 18:58:09 31-7-144-140 proxy[4164]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358192 at voip.positivonet.it:5060 T=sip:0899358192 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 18:58:09 31-7-144-140 proxy[4164]: NOTICE: <script>: Authentication failed, stale nonce - R=sip:voip.positivonet.it:5060 ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 18:58:09 31-7-144-140 proxy[4170]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358192 at voip.positivonet.it:5060 T=sip:0899358192 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 18:58:09 31-7-144-140 proxy[4170]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 18:58:09 31-7-144-140 proxy[4163]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358193 at voip.positivonet.it:5060 T=sip:0899358193 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:58:09 31-7-144-140 proxy[4163]: NOTICE: <script>: Authentication failed, stale nonce - R=sip:voip.positivonet.it:5060 ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:58:09 31-7-144-140 proxy[4168]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358193 at voip.positivonet.it:5060 T=sip:0899358193 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:58:09 31-7-144-140 proxy[4168]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 18:58:21 31-7-144-140 proxy[4171]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358196 at voip.positivonet.it
>> T=sip:0899358196 at voip.positivonet.it IP=31.7.145.184:5060
>> (127.0.0.1:5060) ID=9670c161-3167f90e at 192.168.1.100
>> May 6 18:58:21 31-7-144-140 proxy[4171]: NOTICE: <script>:
>> Authentication failed, stale nonce - R=sip:voip.positivonet.it
>> ID=9670c161-3167f90e at 192.168.1.100
>> May 6 18:58:21 31-7-144-140 proxy[4162]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358196 at voip.positivonet.it
>> T=sip:0899358196 at voip.positivonet.it IP=31.7.145.184:5060
>> (127.0.0.1:5060) ID=9670c161-3167f90e at 192.168.1.100
>> May 6 18:58:21 31-7-144-140 proxy[4162]: ERROR: <core>
>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>> character, ; expected May 6 18:58:21 31-7-144-140 proxy[4162]: ERROR:
>> <core> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May
>> 6 18:58:21 31-7-144-140 proxy[4162]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:58:21 31-7-144-140 proxy[4162]: NOTICE: <script>: Contacts
>> successfully updated, expires in 180s - R=sip:voip.positivonet.it
>> ID=9670c161-3167f90e at 192.168.1.100
>> May 6 18:59:17 31-7-144-140 proxy[4163]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358108 at voip.positivonet.it
>> T=sip:0899358108 at voip.positivonet.it IP=91.214.73.242:5060
>> (127.0.0.1:5060) ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:59:17 31-7-144-140 proxy[4163]: NOTICE: <script>:
>> Authentication failed, stale nonce - R=sip:voip.positivonet.it
>> ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:59:17 31-7-144-140 proxy[4168]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358108 at voip.positivonet.it
>> T=sip:0899358108 at voip.positivonet.it IP=91.214.73.242:5060
>> (127.0.0.1:5060) ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:59:17 31-7-144-140 proxy[4168]: ERROR: <core>
>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>> character, ; expected May 6 18:59:17 31-7-144-140 proxy[4168]: ERROR:
>> <core> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May
>> 6 18:59:17 31-7-144-140 proxy[4168]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:59:17 31-7-144-140 proxy[4168]: NOTICE: <script>: Contacts
>> successfully updated, expires in 180s - R=sip:voip.positivonet.it
>> ID=9c77f273-25332b0c at 91.214.73.242
>> May 6 18:59:41 31-7-144-140 proxy[4162]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358060 at voip.positivonet.it
>> T=sip:0899358060 at voip.positivonet.it IP=31.7.147.23:5060
>> (127.0.0.1:5060) ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:59:41 31-7-144-140 proxy[4162]: NOTICE: <script>:
>> Authentication failed, no credentials - R=sip:voip.positivonet.it
>> ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:59:41 31-7-144-140 proxy[4164]: NOTICE: <script>: New
>> request - M=REGISTER R=sip:voip.positivonet.it
>> F=sip:0899358060 at voip.positivonet.it
>> T=sip:0899358060 at voip.positivonet.it IP=31.7.147.23:5060
>> (127.0.0.1:5060) ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 18:59:41 31-7-144-140 proxy[4164]: ERROR: <core>
>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>> character, ; expected May 6 18:59:41 31-7-144-140 proxy[4164]: ERROR:
>> <core> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar May
>> 6 18:59:41 31-7-144-140 proxy[4164]: ERROR: <core> [lvalue.c:416]:
>> lval_assign(): assignment failed at pos: (198,25-198,50) May 6
>> 18:59:41 31-7-144-140 proxy[4164]: NOTICE: <script>: Contacts
>> successfully updated, expires in 300s - R=sip:voip.positivonet.it
>> ID=gmJnzrJUUke5hxnnX7yTd0R4pOllaNYu
>> May 6 19:00:51 31-7-144-140 proxy[4165]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358192 at voip.positivonet.it:5060 T=sip:0899358192 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 19:00:51 31-7-144-140 proxy[4165]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ZDU3NTBiMDdhZjg5ZjQ3YTBhZTUwMWQ5OGMxMTUyN2M.
>> May 6 19:00:51 31-7-144-140 proxy[4171]: NOTICE: <script>: New request - M=REGISTER R=sip:voip.positivonet.it:5060 F=sip:0899358193 at voip.positivonet.it:5060 T=sip:0899358193 at voip.positivonet.it:5060 IP=151.12.1.70:5060 (127.0.0.1:5060) ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 19:00:51 31-7-144-140 proxy[4171]: NOTICE: <script>: Contacts successfully updated, expires in 180s - R=sip:voip.positivonet.it:5060 ID=ODMzNDkwYjk4MGYyMTdjYzM4MzAzZTQwZmNmMjVjYmU.
>> May 6 19:00:56 31-7-144-140 proxy[4164]: NOTICE: <script>: New
>> request - M=SUBSCRIBE R=sip:127.0.0.1:5062
>> F=sip:0899358190 at voip.positivonet.it
>> T=sip:0899358190 at voip.positivonet.it IP=91.214.72.41:45250
>> (127.0.0.1:5060) ID=MTgwM2ZkOWZmMjJmMGRjZGQ0NDMwYzNhOThiYzlhOWY
>> May 6 19:00:56 31-7-144-140 proxy[4164]: NOTICE: <script>: Handling
>> subscribe request for event 'message-summary' - R=sip:127.0.0.1:5062
>> ID=MTgwM2ZkOWZmMjJmMGRjZGQ0NDMwYzNhOThiYzlhOWY
>>
>> -----Messaggio originale-----
>> Da: Daniel Grotti [mailto:dgrotti at sipwise.com]
>> Inviato: martedì 6 maggio 2014 18.48
>> A: Alessandro Romano - Rp engineering
>> Cc: spce-user at lists.sipwise.com
>> Oggetto: Re: [Spce-user] Log Error
>>
>> Hi Alessandro,
>> I think this is a cosmetic issue.
>> Does it appear during Registration or what?
>> Do you have the full log?
>>
>> Daniel
>>
>> On 6 May 2014 18:23, Alessandro Romano - Rp engineering <alessandro.romano at rpeng.it> wrote:
>>>
>>> Dear All,
>>>
>>> we have currently this error on the logs
>>>
>>>
>>>
>>> May 6 18:20:55 31-7-144-140 proxy[4171]: ERROR: <core>
>>> [parser/parse_param.c:512]: parse_param2(): parse_params(): Invalid
>>> character, ; expected
>>>
>>> May 6 18:20:55 31-7-144-140 proxy[4171]: ERROR: <core>
>>> [lvalue.c:356]: lval_pvar_assign(): non existing right pvar
>>>
>>> May 6 18:20:55 31-7-144-140 proxy[4171]: ERROR: <core>
>>> [lvalue.c:416]: lval_assign(): assignment failed at pos:
>>> (198,25-198,50)
>>>
>>>
>>>
>>> What does it mean ?
>>>
>>>
>>>
>>> BR
>>>
>>> Alessandro
>>
>> _______________________________________________
>> Spce-user mailing list
>> Spce-user at lists.sipwise.com
>> http://lists.sipwise.com/listinfo/spce-user
>
>
>
> _______________________________________________
> Spce-user mailing list
> Spce-user at lists.sipwise.com
> http://lists.sipwise.com/listinfo/spce-user
>
More information about the Spce-user
mailing list