== Debugging ==
[[PageOutline]]

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 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>
}}}