[Spce-user] Log Error

Daniel Grotti dgrotti at sipwise.com
Tue May 6 13:15:14 EDT 2014


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


More information about the Spce-user mailing list