What does this mean? JobHandler_LuaUPnP::REQ_VariableGet variable not found

Community Playground General Discussions
#1

I’m trying to resolve all errors seen in my Vera Logs.

I am seeing this often however there is nothing on this forum or the old one about it.

Any ideas ?

01	09/14/20 14:30:59.786	JobHandler_LuaUPnP::REQ_VariableGet variable not found <0x6e74d520>
01	09/14/20 14:30:59.825	JobHandler_LuaUPnP::REQ_VariableGet variable not found <0x6e024520>
01	09/14/20 14:30:59.861	JobHandler_LuaUPnP::REQ_VariableGet variable not found <0x6e74d520>

I have also been uninstalling old plugins I am not using anymore, such as the DLNA Media plugin and UPNP Event Proxy. I have also manually removed all their plugin files from the /etc/cmh-ludl folder.

I was also seeing errors in the log similar as described by @reneboer on this thread here about stopping UPNP Scanning.

I have since run these two commands and I no longer see them in the log.

luup.attr_set("EnableUPnP", 0, 0)
luup.attr_set("UpnpScanning", 0, 0)

However I am still seeing this “JobHandler_LuaUPnP::REQ_VariableGet variable not found” message in the log.

Thanks

#2

The UPnP event proxy wires itself into the OS at startup. Remove the following files for a complete uninstall: /etc/init.d/upnp-proxy-daemon and /etc/rc.d/*upnp-proxy-daemon (any file in /etc/rc.d with upnp-proxy-daemon at the end of the name).

The REQ_VariableGet messages are likely being caused by something external that is making a Luup request (HTTP) trying to get a variable that doesn’t exist. If you turn on log level 12 (add it to the comma-separated list in /etc/cmh/cmh.conf and reload), it may show you what the request is, so at least you’ll identify the variable. It doesn’t list the source IP of the request, though, so you may still have some guesswork to do.

1 Like
#3

Hi

Thanks for your help. I’m still trying to eliminate all the errors in the Vera log, the ones you told me about on our DM I have gotten rid of all those, except the PLEG ones you can see here, I can’t figure those out at all.

Regarding UPNP Event Proxy, I’m using WinSCP I can’t see any folders or files with “upnp-proxy-daemon” either in the /etc/init.d/ or /etc/rc.d/ folders.

I’ll try the log level 12 now.

Thanks.

#4

OK I’ve added log level 12 and reloaded.

Now I have more detail:

02	09/14/20 15:14:26.657	JobHandler_LuaUPnP::REQ_Handler no handler for lr_LuaView <0x6e4c9520>
12	09/14/20 15:14:26.684	luvd_get_info_data_request starting /data_request?id=lu_variableget&DeviceNum=247&serviceId=urn:micasaverde-org:serviceId:SerialPort1&Variable=baud pMem 0x2b36000/45309952 diff: 30879744 <0x6e4c9520>
12	09/14/20 15:14:26.845	luvd_get_info_data_request starting /data_request?id=lu_variableget&DeviceNum=247&serviceId=urn:micasaverde-org:serviceId:SerialPort1&Variable=stopbits pMem 0x2aa3000/44707840 diff: 30277632 <0x6e4c9520>
01	09/14/20 15:14:26.846	JobHandler_LuaUPnP::REQ_VariableGet variable not found <0x6e4c9520>
12	09/14/20 15:14:26.879	luvd_get_info_data_request starting /data_request?id=lu_variableget&DeviceNum=247&serviceId=urn:micasaverde-org:serviceId:SerialPort1&Variable=databits pMem 0x2aa3000/44707840 diff: 30277632 <0x6e4c9520>
01	09/14/20 15:14:26.880	JobHandler_LuaUPnP::REQ_VariableGet variable not found <0x6e4c9520>
12	09/14/20 15:14:26.911	luvd_get_info_data_request starting /data_request?id=lu_variableget&DeviceNum=247&serviceId=urn:micasaverde-org:serviceId:SerialPort1&Variable=parity pMem 0x2aa3000/44707840 diff: 30277632 <0x6e4c9520>
01	09/14/20 15:14:26.912	JobHandler_LuaUPnP::REQ_VariableGet variable not found <0x6e4c9520>
12	09/14/20 15:14:27.796	luvd_get_info_data_request starting /data_request?id=lu_status2&output_format=json&DataVersion=92707660&LoadTime=1600092707 pMem 0x2aa3000/44707840 diff: 30277632 <0x6e4c9520>
06	09/14/20 15:14:28.110	Device_Variable::m_szValue_set device: 501 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 21.50 now: 20.90 #hooks: 0 upnp: 0 skip: 0 v:0x139f458/NONE duplicate:0 <0x76e18520>
06	09/14/20 15:14:28.250	Device_Variable::m_szValue_set device: 501 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 20.90 now: 20.90 #hooks: 0 upnp: 0 skip: 0 v:0x139f458/NONE duplicate:1 <0x76e18520>
12	09/14/20 15:14:29.657	luvd_get_info_data_request starting /data_request?id=status2 pMem 0x2aa3000/44707840 diff: 30277632 <0x6eac9520>
50	09/14/20 15:14:30.116	luup_log:54: Initializing CurrentCost EnviR <0x77018520>
50	09/14/20 15:14:30.117	luup_log:54: Opening serial port <0x77018520>
50	09/14/20 15:14:30.118	luup_log:54: Power formula: +0 <0x77018520>
12	09/14/20 15:14:30.143	luvd_get_info_data_request starting /data_request?id=status2&LoadTime=1600092854&DataVersion=92854098&Timeout=40&MinimumDelay=0 pMem 0x2aa3000/44707840 diff: 30277632 <0x6e6c9520>

Device number 247 is:

[247 / usb-xhci-hcd-1] pl2303 1

I have a CurrentCost EnviR energy monitor connected to the USB port of my Vera Plus.

[183 / usb-101c1000.ohci-1] pl2303 is the other one, which looks like I am not using. I forget how I set this up.

Here is the serial port configuration:

image

If I enter this in to Chrome browser it works OK and returns the Baud value of “57600”

http://VERA-IP/port_3480/data_request?id=lu_variableget&DeviceNum=247&serviceId=urn:micasaverde-org:serviceId:SerialPort1&Variable=baud

Looks like this one is the problem =stopbits

http://VERA-IP/port_3480/data_request?id=lu_variableget&DeviceNum=247&serviceId=urn:micasaverde-org:serviceId:SerialPort1&Variable=stopbits

Returns:

Error: variable not found.

Same with =databits and =parity

Returns:

Error: variable not found.