fixed - message response signaling and logging
[deb_libcec.git] / src / lib / adapter / Pulse-Eight / USBCECAdapterMessageQueue.cpp
1 /*
2 * This file is part of the libCEC(R) library.
3 *
4 * libCEC(R) is Copyright (C) 2011-2012 Pulse-Eight Limited. All rights reserved.
5 * libCEC(R) is an original work, containing original code.
6 *
7 * libCEC(R) is a trademark of Pulse-Eight Limited.
8 *
9 * This program is dual-licensed; you can redistribute it and/or modify
10 * it under the terms of the GNU General Public License as published by
11 * the Free Software Foundation; either version 2 of the License, or
12 * (at your option) any later version.
13 *
14 * This program is distributed in the hope that it will be useful,
15 * but WITHOUT ANY WARRANTY; without even the implied warranty of
16 * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
17 * GNU General Public License for more details.
18 *
19 * You should have received a copy of the GNU General Public License
20 * along with this program; if not, write to the Free Software
21 * Foundation, Inc., 59 Temple Place - Suite 330, Boston, MA 02111-1307, USA.
22 *
23 *
24 * Alternatively, you can license this library under a commercial license,
25 * please contact Pulse-Eight Licensing for more information.
26 *
27 * For more information contact:
28 * Pulse-Eight Licensing <license@pulse-eight.com>
29 * http://www.pulse-eight.com/
30 * http://www.pulse-eight.net/
31 */
32
33 #include "env.h"
34 #include "USBCECAdapterMessageQueue.h"
35
36 #include "USBCECAdapterCommunication.h"
37 #include "USBCECAdapterMessage.h"
38 #include "lib/platform/sockets/socket.h"
39 #include "lib/LibCEC.h"
40 #include "lib/platform/util/StdString.h"
41
42 using namespace CEC;
43 using namespace PLATFORM;
44 using namespace std;
45
46 #define MESSAGE_QUEUE_SIGNAL_WAIT_TIME 1000
47
48 CCECAdapterMessageQueueEntry::CCECAdapterMessageQueueEntry(CCECAdapterMessageQueue *queue, CCECAdapterMessage *message) :
49 m_queue(queue),
50 m_message(message),
51 m_iPacketsLeft(message->IsTranmission() ? message->Size() / 4 : 1),
52 m_bSucceeded(false),
53 m_bWaiting(true),
54 m_queueTimeout(message->transmit_timeout) {}
55
56 CCECAdapterMessageQueueEntry::~CCECAdapterMessageQueueEntry(void) { }
57
58 void CCECAdapterMessageQueueEntry::Broadcast(void)
59 {
60 CLockObject lock(m_mutex);
61 m_condition.Broadcast();
62 }
63
64 bool CCECAdapterMessageQueueEntry::MessageReceived(const CCECAdapterMessage &message)
65 {
66 bool bHandled(false);
67
68 if (IsResponse(message))
69 {
70 switch (message.Message())
71 {
72 case MSGCODE_COMMAND_ACCEPTED:
73 bHandled = MessageReceivedCommandAccepted(message);
74 break;
75 case MSGCODE_TRANSMIT_SUCCEEDED:
76 bHandled = MessageReceivedTransmitSucceeded(message);
77 break;
78 default:
79 bHandled = MessageReceivedResponse(message);
80 break;
81 }
82 }
83
84 return bHandled;
85 }
86
87 void CCECAdapterMessageQueueEntry::Signal(void)
88 {
89 CLockObject lock(m_mutex);
90 m_bSucceeded = true;
91 m_condition.Signal();
92 }
93
94 bool CCECAdapterMessageQueueEntry::Wait(uint32_t iTimeout)
95 {
96 bool bReturn(false);
97 /* wait until we receive a signal when the tranmission succeeded */
98 {
99 CLockObject lock(m_mutex);
100 bReturn = m_bSucceeded ? true : m_condition.Wait(m_mutex, m_bSucceeded, iTimeout);
101 m_bWaiting = false;
102 }
103 return bReturn;
104 }
105
106 bool CCECAdapterMessageQueueEntry::IsWaiting(void)
107 {
108 CLockObject lock(m_mutex);
109 return m_bWaiting;
110 }
111
112 cec_adapter_messagecode CCECAdapterMessageQueueEntry::MessageCode(void)
113 {
114 return m_message->Message();
115 }
116
117 bool CCECAdapterMessageQueueEntry::IsResponseOld(const CCECAdapterMessage &msg)
118 {
119 cec_adapter_messagecode msgCode = msg.Message();
120
121 return msgCode == MessageCode() ||
122 msgCode == MSGCODE_COMMAND_ACCEPTED ||
123 msgCode == MSGCODE_COMMAND_REJECTED ||
124 (m_message->IsTranmission() && (msgCode == MSGCODE_TIMEOUT_ERROR ||
125 msgCode == MSGCODE_HIGH_ERROR ||
126 msgCode == MSGCODE_LOW_ERROR ||
127 msgCode == MSGCODE_RECEIVE_FAILED ||
128 msgCode == MSGCODE_TRANSMIT_FAILED_LINE ||
129 msgCode == MSGCODE_TRANSMIT_FAILED_ACK ||
130 msgCode == MSGCODE_TRANSMIT_FAILED_TIMEOUT_DATA ||
131 msgCode == MSGCODE_TRANSMIT_FAILED_TIMEOUT_LINE ||
132 msgCode == MSGCODE_TRANSMIT_SUCCEEDED));
133 }
134
135 bool CCECAdapterMessageQueueEntry::IsResponse(const CCECAdapterMessage &msg)
136 {
137 if (m_message->state == ADAPTER_MESSAGE_STATE_SENT_ACKED)
138 return false;
139
140 cec_adapter_messagecode thisMsgCode = m_message->Message();
141 cec_adapter_messagecode msgCode = msg.Message();
142 cec_adapter_messagecode msgResponse = msg.ResponseTo();
143
144 // msgcode matches, always a response
145 if (msgCode == MessageCode())
146 return true;
147
148 if (!ProvidesExtendedResponse())
149 return IsResponseOld(msg);
150
151 // response without a msgcode
152 if (msgResponse == MSGCODE_NOTHING)
153 return false;
154
155 // commands that only repond with accepted/rejected
156 if (thisMsgCode == MSGCODE_PING ||
157 thisMsgCode == MSGCODE_SET_ACK_MASK ||
158 thisMsgCode == MSGCODE_SET_CONTROLLED ||
159 thisMsgCode == MSGCODE_SET_AUTO_ENABLED ||
160 thisMsgCode == MSGCODE_SET_DEFAULT_LOGICAL_ADDRESS ||
161 thisMsgCode == MSGCODE_SET_LOGICAL_ADDRESS_MASK ||
162 thisMsgCode == MSGCODE_SET_PHYSICAL_ADDRESS ||
163 thisMsgCode == MSGCODE_SET_DEVICE_TYPE ||
164 thisMsgCode == MSGCODE_SET_HDMI_VERSION ||
165 thisMsgCode == MSGCODE_SET_OSD_NAME ||
166 thisMsgCode == MSGCODE_WRITE_EEPROM ||
167 thisMsgCode == MSGCODE_TRANSMIT_IDLETIME)
168 return thisMsgCode == msgResponse;
169
170 if (!m_message->IsTranmission())
171 {
172 m_queue->m_com->m_callback->GetLib()->AddLog(CEC_LOG_WARNING, "FIXME! not a transmission");
173 return false;
174 }
175
176 return ((msgCode == MSGCODE_COMMAND_ACCEPTED || msgCode == MSGCODE_COMMAND_REJECTED) &&
177 (msgResponse == MSGCODE_TRANSMIT_ACK_POLARITY || msgResponse == MSGCODE_TRANSMIT || msgResponse == MSGCODE_TRANSMIT_EOM)) ||
178 msgCode == MSGCODE_TIMEOUT_ERROR ||
179 msgCode == MSGCODE_RECEIVE_FAILED ||
180 msgCode == MSGCODE_TRANSMIT_FAILED_ACK ||
181 msgCode == MSGCODE_TRANSMIT_FAILED_TIMEOUT_DATA ||
182 msgCode == MSGCODE_TRANSMIT_FAILED_TIMEOUT_LINE ||
183 msgCode == MSGCODE_TRANSMIT_SUCCEEDED;
184 }
185
186 const char *CCECAdapterMessageQueueEntry::ToString(void) const
187 {
188 /* CEC transmissions got the 'set ack polarity' msgcode, which doesn't look nice */
189 if (m_message->IsTranmission())
190 return "CEC transmission";
191 else
192 return CCECAdapterMessage::ToString(m_message->Message());
193 }
194
195 bool CCECAdapterMessageQueueEntry::MessageReceivedCommandAccepted(const CCECAdapterMessage &message)
196 {
197 bool bSendSignal(false);
198 bool bHandled(false);
199 {
200 CLockObject lock(m_mutex);
201 if (m_iPacketsLeft > 0)
202 {
203 /* decrease by 1 */
204 m_iPacketsLeft--;
205
206 /* log this message */
207 CStdString strLog;
208 strLog.Format("%s - command accepted", ToString());
209 if (m_iPacketsLeft > 0)
210 strLog.AppendFormat(" - waiting for %d more", m_iPacketsLeft);
211 m_queue->m_com->m_callback->GetLib()->AddLog(CEC_LOG_DEBUG, strLog);
212
213 /* no more packets left and not a transmission, so we're done */
214 if (!m_message->IsTranmission() && m_iPacketsLeft == 0)
215 {
216 m_message->state = ADAPTER_MESSAGE_STATE_SENT_ACKED;
217 m_message->response = message.packet;
218 bSendSignal = true;
219 }
220 bHandled = true;
221 }
222 }
223
224 if (bSendSignal)
225 Signal();
226
227 return bHandled;
228 }
229
230 bool CCECAdapterMessageQueueEntry::MessageReceivedTransmitSucceeded(const CCECAdapterMessage &message)
231 {
232 {
233 CLockObject lock(m_mutex);
234 if (m_iPacketsLeft == 0)
235 {
236 /* transmission succeeded, so we're done */
237 m_queue->m_com->m_callback->GetLib()->AddLog(CEC_LOG_DEBUG, "%s - transmit succeeded", m_message->ToString().c_str());
238 m_message->state = ADAPTER_MESSAGE_STATE_SENT_ACKED;
239 m_message->response = message.packet;
240 }
241 else
242 {
243 /* error, we expected more acks
244 since the messages are processed in order, this should not happen, so this is an error situation */
245 m_queue->m_com->m_callback->GetLib()->AddLog(CEC_LOG_WARNING, "%s - received 'transmit succeeded' but not enough 'command accepted' messages (%d left)", ToString(), m_iPacketsLeft);
246 m_message->state = ADAPTER_MESSAGE_STATE_ERROR;
247 }
248 }
249
250 Signal();
251
252 return true;
253 }
254
255 bool CCECAdapterMessageQueueEntry::MessageReceivedResponse(const CCECAdapterMessage &message)
256 {
257 {
258 CLockObject lock(m_mutex);
259 m_queue->m_com->m_callback->GetLib()->AddLog(CEC_LOG_DEBUG, "%s - received response - %s", ToString(), message.ToString().c_str());
260 m_message->response = message.packet;
261 if (m_message->IsTranmission())
262 m_message->state = message.Message() == MSGCODE_TRANSMIT_SUCCEEDED ? ADAPTER_MESSAGE_STATE_SENT_ACKED : ADAPTER_MESSAGE_STATE_SENT_NOT_ACKED;
263 else
264 m_message->state = ADAPTER_MESSAGE_STATE_SENT_ACKED;
265 }
266
267 Signal();
268
269 return true;
270 }
271
272 bool CCECAdapterMessageQueueEntry::ProvidesExtendedResponse(void)
273 {
274 return m_queue && m_queue->ProvidesExtendedResponse();
275 }
276
277 bool CCECAdapterMessageQueueEntry::TimedOutOrSucceeded(void) const
278 {
279 return m_message->bFireAndForget && (m_bSucceeded || m_queueTimeout.TimeLeft() == 0);
280 }
281
282 CCECAdapterMessageQueue::CCECAdapterMessageQueue(CUSBCECAdapterCommunication *com) :
283 PLATFORM::CThread(),
284 m_com(com),
285 m_iNextMessage(0)
286 {
287 m_incomingAdapterMessage = new CCECAdapterMessage;
288 m_currentCECFrame.Clear();
289 }
290
291 CCECAdapterMessageQueue::~CCECAdapterMessageQueue(void)
292 {
293 Clear();
294 StopThread(0);
295 delete m_incomingAdapterMessage;
296 }
297
298 void CCECAdapterMessageQueue::Clear(void)
299 {
300 StopThread(5);
301 CLockObject lock(m_mutex);
302 m_writeQueue.Clear();
303 m_messages.clear();
304 }
305
306 void *CCECAdapterMessageQueue::Process(void)
307 {
308 CCECAdapterMessageQueueEntry *message(NULL);
309 while (!IsStopped())
310 {
311 /* wait for a new message */
312 if (m_writeQueue.Pop(message, MESSAGE_QUEUE_SIGNAL_WAIT_TIME) && message)
313 {
314 /* write this message */
315 {
316 CLockObject lock(m_mutex);
317 m_com->WriteToDevice(message->m_message);
318 }
319 if (message->m_message->state == ADAPTER_MESSAGE_STATE_ERROR ||
320 message->m_message->Message() == MSGCODE_START_BOOTLOADER)
321 {
322 message->Signal();
323 Clear();
324 break;
325 }
326 }
327
328 CheckTimedOutMessages();
329 }
330 return NULL;
331 }
332
333 void CCECAdapterMessageQueue::CheckTimedOutMessages(void)
334 {
335 CLockObject lock(m_mutex);
336 vector<uint64_t> timedOut;
337 for (map<uint64_t, CCECAdapterMessageQueueEntry *>::iterator it = m_messages.begin(); it != m_messages.end(); it++)
338 {
339 if (it->second->TimedOutOrSucceeded())
340 {
341 timedOut.push_back(it->first);
342 if (!it->second->m_bSucceeded)
343 m_com->m_callback->GetLib()->AddLog(CEC_LOG_DEBUG, "command '%s' was not acked by the controller", CCECAdapterMessage::ToString(it->second->m_message->Message()));
344 delete it->second->m_message;
345 delete it->second;
346 }
347 }
348
349 for (vector<uint64_t>::iterator it = timedOut.begin(); it != timedOut.end(); it++)
350 {
351 uint64_t iEntryId = *it;
352 m_messages.erase(iEntryId);
353 }
354 }
355
356 void CCECAdapterMessageQueue::MessageReceived(const CCECAdapterMessage &msg)
357 {
358 bool bHandled(false);
359 CLockObject lock(m_mutex);
360 /* send the received message to each entry in the queue until it is handled */
361 for (map<uint64_t, CCECAdapterMessageQueueEntry *>::iterator it = m_messages.begin(); !bHandled && it != m_messages.end(); it++)
362 bHandled = it->second->MessageReceived(msg);
363
364 if (!bHandled)
365 {
366 /* the message wasn't handled */
367 bool bIsError(m_com->HandlePoll(msg));
368 m_com->m_callback->GetLib()->AddLog(bIsError ? CEC_LOG_WARNING : CEC_LOG_DEBUG, msg.ToString().c_str());
369
370 /* push this message to the current frame */
371 if (!bIsError && msg.PushToCecCommand(m_currentCECFrame))
372 {
373 /* and push the current frame back over the callback method when a full command was received */
374 if (m_com->IsInitialised())
375 m_com->m_callback->OnCommandReceived(m_currentCECFrame);
376
377 /* clear the current frame */
378 m_currentCECFrame.Clear();
379 }
380 }
381 }
382
383 void CCECAdapterMessageQueue::AddData(uint8_t *data, size_t iLen)
384 {
385 for (size_t iPtr = 0; iPtr < iLen; iPtr++)
386 {
387 bool bFullMessage(false);
388 {
389 CLockObject lock(m_mutex);
390 bFullMessage = m_incomingAdapterMessage->PushReceivedByte(data[iPtr]);
391 }
392
393 if (bFullMessage)
394 {
395 /* a full message was received */
396 CCECAdapterMessage newMessage;
397 newMessage.packet = m_incomingAdapterMessage->packet;
398 MessageReceived(newMessage);
399
400 /* clear the current message */
401 CLockObject lock(m_mutex);
402 m_incomingAdapterMessage->Clear();
403 }
404 }
405 }
406
407 bool CCECAdapterMessageQueue::Write(CCECAdapterMessage *msg)
408 {
409 msg->state = ADAPTER_MESSAGE_STATE_WAITING_TO_BE_SENT;
410
411 /* set the correct line timeout */
412 if (msg->IsTranmission())
413 {
414 m_com->SetLineTimeout(msg->lineTimeout);
415 }
416
417 CCECAdapterMessageQueueEntry *entry = new CCECAdapterMessageQueueEntry(this, msg);
418 if (!entry)
419 {
420 m_com->m_callback->GetLib()->AddLog(CEC_LOG_ERROR, "couldn't create queue entry for '%s'", CCECAdapterMessage::ToString(msg->Message()));
421 msg->state = ADAPTER_MESSAGE_STATE_ERROR;
422 return false;
423 }
424
425 uint64_t iEntryId(0);
426 /* add to the wait for ack queue */
427 if (msg->Message() != MSGCODE_START_BOOTLOADER)
428 {
429 CLockObject lock(m_mutex);
430 iEntryId = m_iNextMessage++;
431 m_messages.insert(make_pair(iEntryId, entry));
432 }
433
434 /* add the message to the write queue */
435 m_writeQueue.Push(entry);
436
437 bool bReturn(true);
438 if (!msg->bFireAndForget)
439 {
440 if (!entry->Wait(msg->transmit_timeout <= 5 ? CEC_DEFAULT_TRANSMIT_WAIT : msg->transmit_timeout))
441 {
442 m_com->m_callback->GetLib()->AddLog(CEC_LOG_DEBUG, "command '%s' was not acked by the controller", CCECAdapterMessage::ToString(msg->Message()));
443 msg->state = ADAPTER_MESSAGE_STATE_SENT_NOT_ACKED;
444 bReturn = false;
445 }
446
447 if (msg->Message() != MSGCODE_START_BOOTLOADER)
448 {
449 CLockObject lock(m_mutex);
450 m_messages.erase(iEntryId);
451 }
452
453 if (msg->ReplyIsError())
454 msg->state = ADAPTER_MESSAGE_STATE_ERROR;
455
456 delete entry;
457 }
458
459 return bReturn;
460 }
461
462 bool CCECAdapterMessageQueue::ProvidesExtendedResponse(void)
463 {
464 return m_com && m_com->ProvidesExtendedResponse();
465 }