feat(driver/at): add wall time in debug log to determine timeout issue

Signed-off-by: Coelacanthus <uwu@coelacanthus.name>

Author: CoelacanthusHex

driver/apdu/at_cmd.h

@@ -1,6 +1,7 @@
 #pragma once
 
 #include <cjson/cJSON.h>
+#include <lpac/utils.h>
 
 #define AT_BUFFER_SIZE 20480
 #define AT_READ_BUFFER_SIZE 4096
@@ -12,6 +13,16 @@
 
 struct at_userdata;
 
+#define AT_DEBUG(direct, line)                                                              \
+    do {                                                                                    \
+        if (getenv_or_default(ENV_AT_DEBUG, (bool)false)) {                                 \
+            struct timespec t = get_wall_time(userdata->wall);                              \
+            fprintf(stderr, "AT_DEBUG_" direct "(%d.%d): %s\n", t.tv_sec, t.tv_nsec, line); \
+        }                                                                                   \
+    } while (0)
+#define AT_DEBUG_TX(line) AT_DEBUG("TX", line)
+#define AT_DEBUG_RX(line) AT_DEBUG("RX", line)
+
 int enumerate_serial_device(cJSON *device);
 
 char *get_at_default_device(struct at_userdata *userdata);

driver/apdu/at_cmd_unix.c

@@ -12,6 +12,7 @@
 #include <stdlib.h>
 #include <string.h>
 #include <termios.h> // Required for serial port configuration
+#include <time.h>
 #include <unistd.h>
 
 struct at_userdata {
@@ -23,6 +24,8 @@ struct at_userdata {
     size_t at_read_buffer_len;
 
     char **channels;
+
+    struct timespec wall;
 };
 
 #if defined(__linux__)
@@ -124,8 +127,7 @@ int at_expect(struct at_userdata *userdata, char **response, const char *expecte
         if (strlen(line) == 0)
             continue;
 
-        if (getenv_or_default(ENV_AT_DEBUG, (bool)false))
-            fprintf(stderr, "AT_DEBUG_RX: %s\n", line);
+        AT_DEBUG_RX(line);
 
         if (strcmp(line, "ERROR") == 0) {
             result = -1;

driver/apdu/at_cmd_win32.c

@@ -10,6 +10,7 @@
 #include <stdio.h>
 #include <stdlib.h>
 #include <string.h>
+#include <time.h>
 
 #pragma comment(lib, "setupapi.lib")
 
@@ -21,6 +22,8 @@ struct at_userdata {
     char *default_device;
 
     char **channels;
+
+    struct timespec wall;
 };
 
 int enumerate_serial_device(cJSON *devices) {
@@ -124,8 +127,7 @@ int at_expect(struct at_userdata *userdata, char **response, const char *expecte
             continue;
         }
 
-        if (getenv_or_default(ENV_AT_DEBUG, (bool)false))
-            fprintf(stderr, "AT_DEBUG_RX: %s\n", line);
+        AT_DEBUG_RX(line);
 
         if (strcmp(line, "ERROR") == 0) {
             result = -1;
@@ -201,6 +203,7 @@ int at_setup_userdata(struct at_userdata **userdata) {
     if (*userdata == NULL)
         return -1;
     memset(*userdata, 0, sizeof(struct at_userdata));
+    clock_gettime(CLOCK_MONOTONIC, &(*userdata)->wall);
     (*userdata)->default_device = "COM3";
     (*userdata)->hComm = INVALID_HANDLE_VALUE;
     (*userdata)->at_cmd_buffer = calloc(AT_BUFFER_SIZE, 1);

driver/apdu/at_helpers.c

@@ -77,8 +77,7 @@ int at_emit_command(struct at_userdata *userdata, const char *fmt, ...) {
     formatted[n + 1] = '\n'; // LF
     formatted[n + 2] = '\0'; // NUL
 
-    if (getenv_or_default(ENV_AT_DEBUG, (bool)false))
-        fprintf(stderr, "AT_DEBUG_TX: %s", formatted);
+    AT_DEBUG_TX(formatted);
 
     int ret = at_write_command(userdata, formatted);
     return ret;