Last modified 10 years ago Last modified on 2011-04-19 06:52:29

Debugging

This page contains shows various forms of log output that occur during execution of the IR components in MiOS, specifically those using the SQBlaster Plugin.

The examples shown include both successful requests and executions, along with un-successful executions for the common cases. They are intended as a guide to debugging a particular configuration, since there are many things that may prevent an environment from working correctly.

In most cases, the samples provided will require you to have access to the LuaUPnP.log file. This can be done by logging into your Vera using ssh. Alternatively, on new MiOS Firmware versions, you can view the log files online using the URL:

http://192.168.x.xxx/cgi-bin/cmh/log.sh?Device=LuaUPnP

Separately, if you wish to call an IR Action on an IR Device, this can be done via a URL like:

http://192.168.x.xxx:49451/data_request?id=lu_action&DeviceNum=202&serviceId=urn:micasaverde-com:serviceId:DiscretePower1&action=On

The example above will request DiscretePower1::On, MiOS Device 202. The specific IR Actions that are available will depend upon your installed IR Device. Please look at your I_SQdevxxxxxxx.xml file for other examples (Such as TogglePower1::OnOff)

Note: The log output below may be trimmed in order to make it more readable.

General Health

The SQBlaster Plugin periodically wakes up and emits the following output in the LuaUPnP.log file. This is used to test that the Puck is contactable, and show the corresponding Up or Down status on the Dashboard UI, along with the details show in the Dashboard Dialog.

50 04/17/11 ...	luup_log:90: SQBlaster refreshCache called <0x402>
50 04/17/11 ...	luup_log:90: SQBlaster refreshCache: 0 <0x402>
50 04/17/11 ...	luup_log:90: SQBlaster Successful execution of URL content=
50 04/17/11 ...	luup_log:90: SQBlaster Name sq-blaster-xxxx.local, Firmware Version A052, 
                Date Aug 14 2010, MAC 00:12:b8:00:xx:xx, Antenna N/A, syslog N/A, syslog IP N/A <0x402>

Request to Send an IR Code from the IR Device

The following example shows a DiscretePower1::On action being requested against MiOS Device 202. This request came from a SQRemote AV Control request.

Internally, at the end of this request, MiOS will determine the correct IR ProntoCode to send, and push it through to the associated IRTransmitter (see below)

12 04/17/11 ...	luvd_get_info_data_request starting /data_request?id=lu_action&DeviceNum=202
                &serviceId=urn:micasaverde-com:serviceId:DiscretePower1&action=On
08 04/17/11 ...	JobHandler_LuaUPnP::HandleActionRequest
                device: 202 service: urn:micasaverde-com:serviceId:DiscretePower1 action: On
08 04/17/11 ...	JobHandler_LuaUPnP::HandleActionRequest
                argument DeviceNum=202
08 04/17/11 ...	JobHandler_LuaUPnP::HandleActionRequest
                argument serviceId=urn:micasaverde-com:serviceId:DiscretePower1
08 04/17/11 ...	JobHandler_LuaUPnP::HandleActionRequest
                argument action=On

Request to Send an IR Code from the IR Device - No IRTransmitter Associated (Device not ready)

This is typically what you'll see when there's no IRTransmitter associated with your IRDevice, after attempting to "call" one of it's IR Actions.

It also happens when your IRDevice Plugin (D_SQdevxxxxxxxx.xml) is missing it's implementation file (I_SQdevxxxxxxxx.xml). The names must match the declaration in the <implementationFile> XML element in the Plugin declaration file. In this case you'll also get an error, early in the MiOS Startup processes, where it indicates that the Device has no Implementation.

12 04/17/11 ... luvd_get_info_data_request starting /data_request?id=lu_action&DeviceNum=86
                &serviceId=urn:micasaverde-com:serviceId:TogglePower1&action=OnOff
08 04/17/11 ... JobHandler_LuaUPnP::HandleActionRequest
                device: 86 service: urn:micasaverde-com:serviceId:TogglePower1 action: OnOff
08 04/17/11 ... JobHandler_LuaUPnP::HandleActionRequest
                argument DeviceNum=86
08 04/17/11 ... JobHandler_LuaUPnP::HandleActionRequest
                argument serviceId=urn:micasaverde-com:serviceId:TogglePower1
08 04/17/11 ... JobHandler_LuaUPnP::HandleActionRequest
                argument action=OnOff
02 04/17/11 ... Device_LuaUPnP::HandleActionRequest 86 not ready
02 04/17/11 ... JobHandler_LuaUPnP::RunAction device 86 action urn:micasaverde-com:serviceId:TogglePower1/OnOff
                failed with -911/Device not ready

Request to Send an IR Code from the IR Transmitter

The following example shows an IR ProntoCode being passed through from a MiOS IRDevice, to the attached MiOS IRTransmitter device.

08 04/17/11 ... JobHandler_LuaUPnP::HandleActionRequest device: 90
                service: urn:micasaverde-com:serviceId:IrTransmitter1 action: SendProntoCode <0x380f>
08 04/17/11 ... JobHandler_LuaUPnP::HandleActionRequest argument ProntoCode=P44c0 301c 17b6 7ca4 6e8a 7b96
                cfc2 2234 5204 d2f0 188d 3de6 bc60 1d22 9694 1ccc 83f4 3009 31a2 277c 4ae1 e277 61ac f79e
                83f4 3009 31a2 277c 4ae1 e277 61ac f79e 5204 d2f0 188d 3de6 bc60 1d22 9694 1ccc 83f4 3009
                31a2 277c 4ae1 e277 61ac f79e bf94 1d27 3d8f cfb4 81c4 6171 95b8 abd0 83f4 3009 31a2 277c
                4ae1 e277 61ac f79e 83f4 3009 31a2 277c 4ae1 e277 61ac f79e 9867 f1d1 545b 5d71 1e9a 17f6
                a50e f196 5204 d2f0 188d 3de6 bc60 1d22 9694 1ccc 9867 f1d1 545b 5d71 1e9a 17f6 a50e f196
                c0db 2848 da61 776c 9eac 142c 3ff9 172f  <0x380f>

IR Transmitter succeeded in sending IR Code

50 04/17/11 ... luup_log:90: Web request returned status=200 response=
                in 0ms <0x380f>

IR Transmitter failing to send an IR Code, SQBlaster not contactable

01 04/17/11 ... luup_log:90: Web request returned no response ProntoCode=P44c0 301c 17b6 7ca4 6e8a 7b96
                cfc2 2234 5204 d2f0 188d 3de6 bc60 1d22 9694 1ccc 83f4 3009 31a2 277c 4ae1 e277 61ac f79e
                83f4 3009 31a2 277c 4ae1 e277 61ac f79e 5204 d2f0 188d 3de6 bc60 1d22 9694 1ccc 83f4 3009
                31a2 277c 4ae1 e277 61ac f79e bf94 1d27 3d8f cfb4 81c4 6171 95b8 abd0 83f4 3009 31a2 277c
                4ae1 e277 61ac f79e 83f4 3009 31a2 277c 4ae1 e277 61ac f79e 9867 f1d1 545b 5d71 1e9a 17f6
                a50e f196 5204 d2f0 188d 3de6 bc60 1d22 9694 1ccc 9867 f1d1 545b 5d71 1e9a 17f6 a50e f196
                c0db 2848 da61 776c 9eac 142c 3ff9 172f   in 0ms <0x380f>