X-Git-Url: https://git.ucc.asn.au/?a=blobdiff_plain;f=Kernel%2Fdebug.c;h=f59704ad5bb552747d7e26ae8ae84189c2091331;hb=a2b49f92646ef666e17172faf72a9dcc0d18942a;hp=3a03842466aa97f2442c9c9e62ace9d1272be0d8;hpb=dea6bcf35a3f52396724d74e47f71cb3afade37c;p=tpg%2Facess2.git diff --git a/Kernel/debug.c b/Kernel/debug.c index 3a038424..f59704ad 100644 --- a/Kernel/debug.c +++ b/Kernel/debug.c @@ -1,6 +1,8 @@ /* * AcessOS Microkernel Version * debug.c + * + * TODO: Move the Debug_putchar methods out to the arch/ tree */ #include #include @@ -9,9 +11,10 @@ #define DEBUG_TO_SERIAL 1 #define SERIAL_PORT 0x3F8 #define GDB_SERIAL_PORT 0x2F8 -#define DEBUG_USE_VSNPRINTF 0 #define DEBUG_MAX_LINE_LEN 256 +#define LOCK_DEBUG_OUTPUT 1 + // === IMPORTS === extern void Threads_Dump(void); extern void KernelPanic_SetMode(void); @@ -21,8 +24,11 @@ extern void KernelPanic_PutChar(char Ch); int putDebugChar(char ch); int getDebugChar(void); static void Debug_Putchar(char ch); -static void Debug_Puts(int DbgOnly, char *Str); +static void Debug_Puts(int DbgOnly, const char *Str); +void Debug_DbgOnlyFmt(const char *format, va_list args); +void Debug_FmtS(const char *format, ...); void Debug_Fmt(const char *format, va_list args); +void Debug_SetKTerminal(const char *File); // === GLOBALS === int gDebug_Level = 0; @@ -31,6 +37,9 @@ void Debug_Fmt(const char *format, va_list args); int gbGDB_SerialSetup = 0; int gbDebug_IsKPanic = 0; volatile int gbInPutChar = 0; +#if LOCK_DEBUG_OUTPUT +tShortSpinlock glDebug_Lock; +#endif // === CODE === int putDebugChar(char ch) @@ -38,9 +47,9 @@ int putDebugChar(char ch) if(!gbGDB_SerialSetup) { outb(GDB_SERIAL_PORT + 1, 0x00); // Disable all interrupts outb(GDB_SERIAL_PORT + 3, 0x80); // Enable DLAB (set baud rate divisor) - outb(GDB_SERIAL_PORT + 0, 0x03); // Set divisor to 3 (lo byte) 38400 baud - outb(GDB_SERIAL_PORT + 1, 0x00); // (hi byte) - outb(GDB_SERIAL_PORT + 3, 0x03); // 8 bits, no parity, one stop bit + outb(GDB_SERIAL_PORT + 0, 0x0C); // Set divisor to 12 (lo byte) 9600 baud + outb(GDB_SERIAL_PORT + 1, 0x00); // (base is (hi byte) + outb(GDB_SERIAL_PORT + 3, 0x03); // 8 bits, no parity, one stop bit (8N1) outb(GDB_SERIAL_PORT + 2, 0xC7); // Enable FIFO with 14-byte threshold and clear it outb(GDB_SERIAL_PORT + 4, 0x0B); // IRQs enabled, RTS/DSR set gbDebug_SerialSetup = 1; @@ -54,8 +63,8 @@ int getDebugChar(void) if(!gbGDB_SerialSetup) { outb(GDB_SERIAL_PORT + 1, 0x00); // Disable all interrupts outb(GDB_SERIAL_PORT + 3, 0x80); // Enable DLAB (set baud rate divisor) - outb(GDB_SERIAL_PORT + 0, 0x03); // Set divisor to 3 (lo byte) 38400 baud - outb(GDB_SERIAL_PORT + 1, 0x00); // (hi byte) + outb(GDB_SERIAL_PORT + 0, 0x0C); // Set divisor to 12 (lo byte) 9600 baud + outb(GDB_SERIAL_PORT + 1, 0x00); // (hi byte) outb(GDB_SERIAL_PORT + 3, 0x03); // 8 bits, no parity, one stop bit outb(GDB_SERIAL_PORT + 2, 0xC7); // Enable FIFO with 14-byte threshold and clear it outb(GDB_SERIAL_PORT + 4, 0x0B); // IRQs enabled, RTS/DSR set @@ -75,8 +84,8 @@ static void Debug_PutCharDebug(char ch) if(!gbDebug_SerialSetup) { outb(SERIAL_PORT + 1, 0x00); // Disable all interrupts outb(SERIAL_PORT + 3, 0x80); // Enable DLAB (set baud rate divisor) - outb(SERIAL_PORT + 0, 0x03); // Set divisor to 3 (lo byte) 38400 baud - outb(SERIAL_PORT + 1, 0x00); // (hi byte) + outb(SERIAL_PORT + 0, 0x0C); // Set divisor to 12 (lo byte) 9600 baud + outb(SERIAL_PORT + 1, 0x00); // (hi byte) outb(SERIAL_PORT + 3, 0x03); // 8 bits, no parity, one stop bit outb(SERIAL_PORT + 2, 0xC7); // Enable FIFO with 14-byte threshold and clear it outb(SERIAL_PORT + 4, 0x0B); // IRQs enabled, RTS/DSR set @@ -102,7 +111,7 @@ static void Debug_Putchar(char ch) KernelPanic_PutChar(ch); } -static void Debug_Puts(int UseKTerm, char *Str) +static void Debug_Puts(int UseKTerm, const char *Str) { int len = 0; while( *Str ) @@ -149,6 +158,14 @@ void Debug_Fmt(const char *format, va_list args) return ; } +void Debug_FmtS(const char *format, ...) +{ + va_list args; + va_start(args, format); + Debug_Fmt(format, args); + va_end(args); +} + void Debug_KernelPanic() { gbDebug_IsKPanic = 1; @@ -156,64 +173,106 @@ void Debug_KernelPanic() } /** - * \fn void LogF(char *Msg, ...) + * \fn void LogF(const char *Msg, ...) + * \brief Raw debug log (no new line, no prefix) */ -void LogF(char *Fmt, ...) +void LogF(const char *Fmt, ...) { va_list args; + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif + va_start(args, Fmt); Debug_Fmt(Fmt, args); va_end(args); + + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } /** - * \fn void Debug(char *Msg, ...) + * \fn void Debug(const char *Msg, ...) * \brief Print only to the debug channel */ -void Debug(char *Fmt, ...) +void Debug(const char *Fmt, ...) { va_list args; + + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif Debug_Puts(0, "Debug: "); va_start(args, Fmt); Debug_DbgOnlyFmt(Fmt, args); va_end(args); + Debug_PutCharDebug('\r'); Debug_PutCharDebug('\n'); + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } /** - * \fn void Log(char *Msg, ...) + * \fn void Log(const char *Msg, ...) */ -void Log(char *Fmt, ...) +void Log(const char *Fmt, ...) { va_list args; + + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif Debug_Puts(1, "Log: "); va_start(args, Fmt); Debug_Fmt(Fmt, args); va_end(args); + Debug_Putchar('\r'); Debug_Putchar('\n'); + + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } -void Warning(char *Fmt, ...) +void Warning(const char *Fmt, ...) { va_list args; + + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif + Debug_Puts(1, "Warning: "); va_start(args, Fmt); Debug_Fmt(Fmt, args); va_end(args); + Debug_Putchar('\r'); Debug_Putchar('\n'); + + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } -void Panic(char *Fmt, ...) +void Panic(const char *Fmt, ...) { va_list args; + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif + // And never SHORTREL + Debug_KernelPanic(); Debug_Puts(1, "Panic: "); va_start(args, Fmt); Debug_Fmt(Fmt, args); va_end(args); + Debug_Putchar('\r'); Debug_Putchar('\n'); Threads_Dump(); @@ -223,7 +282,7 @@ void Panic(char *Fmt, ...) for(;;) __asm__ __volatile__ ("hlt"); } -void Debug_SetKTerminal(char *File) +void Debug_SetKTerminal(const char *File) { int tmp; if(giDebug_KTerm != -1) { @@ -237,37 +296,50 @@ void Debug_SetKTerminal(char *File) Log_Log("Debug", "Returning to %p", __builtin_return_address(0)); } -void Debug_Enter(char *FuncName, char *ArgTypes, ...) +void Debug_Enter(const char *FuncName, const char *ArgTypes, ...) { va_list args; - int i = gDebug_Level ++; + int i; int pos; + tTID tid = Threads_GetTID(); + + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif + + i = gDebug_Level ++; va_start(args, ArgTypes); + LogF("%012lli ", now()); while(i--) Debug_Putchar(' '); - Debug_Puts(1, FuncName); Debug_Puts(1, ": ("); + Debug_Puts(1, FuncName); + Debug_FmtS("[%i]", tid); + Debug_Puts(1, ": ("); while(*ArgTypes) { pos = strpos(ArgTypes, ' '); - if(pos != -1) ArgTypes[pos] = '\0'; if(pos == -1 || pos > 1) { - Debug_Puts(1, ArgTypes+1); + if(pos == -1) + Debug_Puts(1, ArgTypes+1); + else { + for( i = 1; i < pos; i ++ ) + Debug_Putchar(ArgTypes[i]); + } Debug_Putchar('='); } - if(pos != -1) ArgTypes[pos] = ' '; switch(*ArgTypes) { - case 'p': Debug_Fmt("%p", args); break; - case 's': Debug_Fmt("'%s'", args); break; - case 'i': Debug_Fmt("%i", args); break; - case 'u': Debug_Fmt("%u", args); break; - case 'x': Debug_Fmt("0x%x", args); break; - case 'b': Debug_Fmt("0b%b", args); break; - case 'X': Debug_Fmt("0x%llx", args); break; // Extended (64-Bit) - case 'B': Debug_Fmt("0b%llb", args); break; // Extended (64-Bit) + case 'p': LogF("%p", va_arg(args, void*)); break; + case 's': LogF("'%s'", va_arg(args, char*)); break; + case 'i': LogF("%i", va_arg(args, int)); break; + case 'u': LogF("%u", va_arg(args, Uint)); break; + case 'x': LogF("0x%x", va_arg(args, Uint)); break; + case 'b': LogF("0b%b", va_arg(args, Uint)); break; + case 'X': LogF("0x%llx", va_arg(args, Uint64)); break; // Extended (64-Bit) + case 'B': LogF("0b%llb", va_arg(args, Uint64)); break; // Extended (64-Bit) } if(pos != -1) { Debug_Putchar(','); Debug_Putchar(' '); @@ -278,29 +350,53 @@ void Debug_Enter(char *FuncName, char *ArgTypes, ...) } va_end(args); - Debug_Putchar(')'); Debug_Putchar('\n'); + Debug_Putchar(')'); Debug_Putchar('\r'); Debug_Putchar('\n'); + + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } -void Debug_Log(char *FuncName, char *Fmt, ...) +void Debug_Log(const char *FuncName, const char *Fmt, ...) { va_list args; int i = gDebug_Level; + tTID tid = Threads_GetTID(); + + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif va_start(args, Fmt); + LogF("%012lli ", now()); while(i--) Debug_Putchar(' '); - Debug_Puts(1, FuncName); Debug_Puts(1, ": "); + Debug_Puts(1, FuncName); + Debug_FmtS("[%i]", tid); + Debug_Puts(1, ": "); Debug_Fmt(Fmt, args); va_end(args); + Debug_Putchar('\r'); Debug_Putchar('\n'); + + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } -void Debug_Leave(char *FuncName, char RetType, ...) +void Debug_Leave(const char *FuncName, char RetType, ...) { va_list args; - int i = --gDebug_Level; + int i; + tTID tid = Threads_GetTID(); + + #if LOCK_DEBUG_OUTPUT + SHORTLOCK(&glDebug_Lock); + #endif + + i = --gDebug_Level; va_start(args, RetType); @@ -308,14 +404,21 @@ void Debug_Leave(char *FuncName, char RetType, ...) gDebug_Level = 0; i = 0; } + LogF("%012lli ", now()); // Indenting while(i--) Debug_Putchar(' '); - Debug_Puts(1, FuncName); Debug_Puts(1, ": RETURN"); + Debug_Puts(1, FuncName); + Debug_FmtS("[%i]", tid); + Debug_Puts(1, ": RETURN"); // No Return if(RetType == '-') { + Debug_Putchar('\r'); Debug_Putchar('\n'); + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif return; } @@ -331,21 +434,27 @@ void Debug_Leave(char *FuncName, char RetType, ...) // Extended (64-Bit) case 'X': Debug_Fmt("0x%llx", args); break; } + Debug_Putchar('\r'); Debug_Putchar('\n'); va_end(args); + + #if LOCK_DEBUG_OUTPUT + SHORTREL(&glDebug_Lock); + #endif } -void Debug_HexDump(char *Header, void *Data, Uint Length) +void Debug_HexDump(const char *Header, const void *Data, Uint Length) { - Uint8 *cdat = Data; + const Uint8 *cdat = Data; Uint pos = 0; Debug_Puts(1, Header); - LogF(" (Hexdump of %p)\n", Data); + LogF(" (Hexdump of %p)\r\n", Data); + + #define CH(n) ((' '<=cdat[(n)]&&cdat[(n)]<=0x7F) ? cdat[(n)] : '.') while(Length >= 16) { - #define CH(n) ((' '<=cdat[(n)]&&cdat[(n)]<=0x7F) ? cdat[(n)] : '.') Log("%04x: %02x %02x %02x %02x %02x %02x %02x %02x" " %02x %02x %02x %02x %02x %02x %02x %02x" " %c%c%c%c%c%c%c%c %c%c%c%c%c%c%c%c", @@ -360,14 +469,23 @@ void Debug_HexDump(char *Header, void *Data, Uint Length) pos += 16; } - LogF("Log: %04x: ", pos); - while(Length) { - Uint byte = *cdat; - LogF("%02x ", byte); - Length--; - cdat ++; + int i ; + LogF("Log: %04x: ", pos); + for(i = 0; i < Length; i ++) + { + LogF("%02x ", cdat[i]); + } + for( ; i < 16; i ++) LogF(" "); + LogF(" "); + for(i = 0; i < Length; i ++) + { + if( i == 8 ) LogF(" "); + LogF("%c", CH(i)); + } } + + Debug_Putchar('\r'); Debug_Putchar('\n'); }