[OpenSER-Devel] [ openser-Bugs-1856098 ] CRITICAL:core:comp_scriptvar: cannot get left var value

SourceForge.net noreply at sourceforge.net
Sun Jan 6 20:25:05 UTC 2008


Bugs item #1856098, was opened at 2007-12-21 23:52
Message generated for change (Comment added) made by miconda
You can respond by visiting: 
https://sourceforge.net/tracker/?func=detail&atid=743020&aid=1856098&group_id=139143

Please note that this message will contain a full copy of the comment thread,
including the initial issue submission, for this request,
not just the latest update.
Category: core
Group: ver devel
Status: Open
Resolution: None
Priority: 5
Private: No
Submitted By: Norm Brandinger (norm_brandinger)
>Assigned to: Daniel-Constantin Mierla (miconda)
Summary: CRITICAL:core:comp_scriptvar: cannot get left var value

Initial Comment:
SUBSCRIBE Linksys SPA9000 / OpenSER devel / Asterisk

At the top of the failure_route(), the following two messages are produced:

CRITICAL:core:comp_scriptvar: cannot get left var value
WARNING:core:do_action: error in expression

Below is the log before and just after the time that the error messages are produced:

Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_msg: SIP Reply  (status):
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_msg:  version: <SIP/2.0>
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_msg:  status:  <404>
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_msg:  reason:  <Not Found>
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: flags=2
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bKf6bc.eb43c572.0>; state=6
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via_param: found param type 236, <i> = <1>; state=6
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via_param: found param type 234, <received> = <209.102.227.28>; state=16
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via: end of header reached, state=5
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: via found, flags=2
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: this is the first via
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:receive_msg: After parse_msg...
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:forward_reply: found module nathelper, passing reply to it
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: flags=4
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via_param: found param type 235, <rport> = <59804>; state=6
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via_param: found param type 234, <received> = <209.102.227.253>; state=6
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via_param: found param type 232, <branch> = <z9hG4bK-3517def0>; state=16
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_via: end of header reached, state=5
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: via found, flags=4
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: parse_headers: this is the second via
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:forward_reply: found module tm, passing reply to it
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_check: start=0xffffffff
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: flags=22
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_to_param: tag=as36ff7b22
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_to: end of header reached, state=29
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_to: display={GOES}, ruri={sip:9087472079 at openser3.goes.com}
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:get_hdr_field: <To> [56]; uri=[sip:9087472079 at openser3.goes.com]
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:get_hdr_field: to body [GOES <sip:9087472079 at openser3.goes.com>]
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:get_hdr_field: cseq <CSeq>: <16553> <SUBSCRIBE>
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_reply_matching: hash 52079 label 660354238 branch 0
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_reply_matching: REF_UNSAFE: after is 1
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_reply_matching: reply matched (T=0xa7a1a948)!
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:run_trans_callbacks: trans=0xa7a1a948, callback type 2, id 1 entered
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_to_param: tag=c4dfa718c46b13c6
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_to: end of header reached, state=29
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_to: display={GOES}, ruri={sip:9087472079 at openser3.goes.com}
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:parse_headers: flags=40
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:siptrace:trace_onreply_in: storing info...
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_check: end=0xa7a1a948
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:reply_received: org. status uas=0, uac[0]=0 local=0 is_invite=0)
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:run_transformations: return val is -1
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: CRITICAL:core:comp_scriptvar: cannot get left var value
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: WARNING:core:do_action: error in expression
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_should_relay_response: T_code=0, new_code=404
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:tm:t_pick_branch: picked branch 0, code 404
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:run_transformations: return val is 0
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:comp_scriptvar: int 27 : 99 / 1
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: 3 [SUBSCRIBE][RELAY_FAILURE] S=<null> D=<null> F=sip:9087472079 at openser3.goes.com T=sip:9087472079 at openser3.goes.com IP=209.102.227.253 ID=5d193f00-6ba81cfe at 192.168.1.15
Dec 21 16:37:37 openser3 /usr/sbin/openser[19805]: DBG:core:run_transformations: return val is 0

----------------------------------------------------------------------

>Comment By: Daniel-Constantin Mierla (miconda)
Date: 2008-01-06 22:25

Message:
Logged In: YES 
user_id=1246013
Originator: NO

Seems to be related to a transformation, which returns error. Can you
apply the attached patch and send me the line in your config file that will
be printed in log message? You will see:

WARNING:core:do_action: error in expression (l=XYZ)

XYZ is the line.
File Added: action.patch

----------------------------------------------------------------------

Comment By: Norm Brandinger (norm_brandinger)
Date: 2007-12-22 17:13

Message:
Logged In: YES 
user_id=1786021
Originator: YES

Below is a another log, this time, the SUBSCRIBE is responded to with an
OK (the prior log was a 404):

Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_msg: SIP
Reply  (status):
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_msg: 
version: <SIP/2.0>
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_msg: 
status:  <200>
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_msg: 
reason:  <OK>
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
flags=2
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_via_param: found param type 232, <branch> =
<z9hG4bK4387.af65eb57.0>; state=6
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_via_param: found param type 236, <i> = <2>; state=6
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_via_param: found param type 234, <received> =
<209.102.227.28>; state=16
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_via: end
of header reached, state=5
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
via found, flags=2
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
this is the first via
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:receive_msg:
After parse_msg...
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:forward_reply:
found module nathelper, passing reply to it
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
flags=4
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_via_param: found param type 235, <rport> = <5060>; state=6
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_via_param: found param type 234, <received> =
<209.102.227.253>; state=6
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_via_param: found param type 232, <branch> =
<z9hG4bK-63085cfc>; state=16
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_via: end
of header reached, state=5
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
via found, flags=4
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
parse_headers: this is the second via
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:forward_reply:
found module tm, passing reply to it
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:tm:t_check:
start=0xffffffff
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
flags=22
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_to_param: tag=as5a4175d9
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_to: end
of header reached, state=29
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_to:
display={GOES}, ruri={sip:9087472079 at openser3.goes.com}
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:get_hdr_field:
<To> [56]; uri=[sip:9087472079 at openser3.goes.com]
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:get_hdr_field:
to body [GOES <sip:9087472079 at openser3.goes.com>]
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:get_hdr_field:
cseq <CSeq>: <25897> <SUBSCRIBE>
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:tm:t_reply_matching: hash 30772 label 1975408378 branch 0
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:tm:t_reply_matching: REF_UNSAFE: after is 1
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:tm:t_reply_matching: reply matched (T=0xa7a37ba0)!
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:tm:run_trans_callbacks: trans=0xa7a37ba0, callback type 2, id 1
entered
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:parse_to_param: tag=6bf33900150356ab
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_to: end
of header reached, state=29
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_to:
display={GOES}, ruri={sip:9087472079 at openser3.goes.com}
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
flags=40
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:siptrace:trace_onreply_in: storing info...
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:tm:t_check:
end=0xa7a37ba0
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:tm:reply_received:
org. status uas=0, uac[0]=0 local=0 is_invite=0)
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:core:run_transformations: return val is -1
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
CRITICAL:core:comp_scriptvar: cannot get left var value
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: WARNING:core:do_action:
error in expression
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]:
DBG:tm:t_should_relay_response: T_code=0, new_code=200
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:tm:relay_reply:
branch=0, save=0, relay=0
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:parse_headers:
flags=2000
Dec 22 10:10:02 openser3 /usr/sbin/openser[21389]: DBG:core:get_hdr_field:
content_length=0


----------------------------------------------------------------------

You can respond by visiting: 
https://sourceforge.net/tracker/?func=detail&atid=743020&aid=1856098&group_id=139143



More information about the Devel mailing list