Skip to content

bug(lib>shuvlog): Console sink out-of-sync output between stdout and stderr #10

Open
Bug
Open
bug(lib>shuvlog): Console sink out-of-sync output between stdout and stderr#10
Bug
Assignees
Shuvlyy
@Shuvlyy

Description

@Shuvlyy
Shuvlyy
opened on Dec 9, 2025

LogFileSink output:

/*************************************************
|
|   Project            :  rtnt (tests)
|   Version            :  0.0.1
|   Build type         :  Debug
|
|   Filter mode        :  All
|   Level(s)           :  None
|
|   Command            :  /Users/ly/Epitech/Year03/CPP/rtype/lib/rtnt/cmake-build-debug/tests/rtnt_tests
|   Start time         :  2025-12-09 10:48:24.793
|
|   OS                 :  macOS 26.1 (build 25B78)
|   Compiler           :  AppleClang 17.0.0.17000404
|   Compilation flags  :  -stdlib=libc++ 
|   Build system       :  CMake 4.0.2
|
\*************************************************

2025-12-09 10:48:24.849 [MainThread]     INFO: [basic] Starting test. (tests/Tests.h:51:56)
2025-12-09 10:48:24.849 [MainThread]    DEBUG: Initializing server and client (tests/tests/Echo.cpp:16:47)
2025-12-09 10:48:24.849 [MainThread]    DEBUG: Setting onMessage function of server (tests/tests/Echo.cpp:23:53)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Starting server (tests/tests/Echo.cpp:37:32)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Will run context in another thread (tests/tests/Echo.cpp:40:51)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Now waiting 400ms (tests/tests/Echo.cpp:47:34)
2025-12-09 10:48:24.850 [IoThread]    DEBUG: Running context (tests/tests/Echo.cpp:43:36)
2025-12-09 10:48:25.254 [MainThread]    DEBUG: Will connect (tests/tests/Echo.cpp:65:29)
2025-12-09 10:48:25.254 [MainThread]     INFO: Connecting to server 127.0.0.1:4242... (src/Core/Client.cpp:19:55)
2025-12-09 10:48:25.254 [MainThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:25.254 [MainThread]    DEBUG: Client sending Packet #1 __rtnt_internal_CONNECT... (include/rtnt/Core/Client.hpp:91:9)
2025-12-09 10:48:25.254 [MainThread] TRACE_R3: Preparing to send a packet.
Sequence ID: 0
Acknowledge ID: 0
Acknowledge bitfield: 0
Message ID: 1
Flags: 2
Payload Size: 1
Checksum: 0
Buffer (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:123:5)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Sent 26 bytes to 127.0.0.1:4242. (src/Core/Peer.cpp:27:13)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Received 26 bytes from 127.0.0.1:56981. (src/Core/Peer.cpp:53:13)
2025-12-09 10:48:25.254 [IoThread]    DEBUG: Session 127.0.0.1 connected (tests/tests/Echo.cpp:34:93)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Handling incoming raw data
Size: 26 bytes
Data (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:30:5)
2025-12-09 10:48:25.254 [IoThread]    DEBUG: Server received a message! (tests/tests/Echo.cpp:25:47)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Preparing to send a packet.
Sequence ID: 0
Acknowledge ID: 0
Acknowledge bitfield: 0
Message ID: 1
Flags: 2
Payload Size: 1
Checksum: 0
Buffer (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:123:5)
2025-12-09 10:48:25.254 [IoThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Sent 26 bytes to 127.0.0.1:56981. (src/Core/Peer.cpp:27:13)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Received 26 bytes from 127.0.0.1:4242. (src/Core/Peer.cpp:53:13)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Handling incoming raw data
Size: 26 bytes
Data (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:30:5)
+2025-12-09 10:48:25.254 [IoThread]    ERROR: Packet Underflow (tests/tests/Echo.cpp:61:35)
2025-12-09 10:48:25.255 [IoThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:27.259 [MainThread]     INFO: [basic] Test ended: KO. (tests/Tests.h:55:100)
2025-12-09 10:48:27.259 [MainThread]     INFO: [assert] Starting test. (tests/Tests.h:51:56)
2025-12-09 10:48:27.259 [MainThread]     INFO: [assert] Test ended: OK. (tests/Tests.h:55:100)
2025-12-09 10:48:27.259 [MainThread]     INFO: Shutting down Logger, will dump remaining logs (2). (/Users/ly/Epitech/Year03/CPP/rtype/lib/shuvlog/src/Logger.cpp:165:83)

ConsoleSink output (OOS is highlighted in red):

/Users/ly/Epitech/Year03/CPP/rtype/lib/rtnt/cmake-build-debug/tests/rtnt_tests --gtest_filter=* --gtest_color=no
Testing started at 10:48 AM ...
2025-12-09 10:48:24.849 [MainThread]     INFO: [basic] Starting test. (tests/Tests.h:51:56)
2025-12-09 10:48:24.849 [MainThread]    DEBUG: Initializing server and client (tests/tests/Echo.cpp:16:47)
2025-12-09 10:48:24.849 [MainThread]    DEBUG: Setting onMessage function of server (tests/tests/Echo.cpp:23:53)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Starting server (tests/tests/Echo.cpp:37:32)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Will run context in another thread (tests/tests/Echo.cpp:40:51)
2025-12-09 10:48:24.850 [MainThread]    DEBUG: Now waiting 400ms (tests/tests/Echo.cpp:47:34)
2025-12-09 10:48:24.850 [IoThread]    DEBUG: Running context (tests/tests/Echo.cpp:43:36)
2025-12-09 10:48:25.254 [MainThread]    DEBUG: Will connect (tests/tests/Echo.cpp:65:29)
2025-12-09 10:48:25.254 [MainThread]     INFO: Connecting to server 127.0.0.1:4242... (src/Core/Client.cpp:19:55)
2025-12-09 10:48:25.254 [MainThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:25.254 [MainThread]    DEBUG: Client sending Packet #1 __rtnt_internal_CONNECT... (include/rtnt/Core/Client.hpp:91:9)
2025-12-09 10:48:25.254 [MainThread] TRACE_R3: Preparing to send a packet.
Sequence ID: 0
Acknowledge ID: 0
Acknowledge bitfield: 0
Message ID: 1
Flags: 2
Payload Size: 1
Checksum: 0
-2025-12-09 10:48:25.254 [IoThread]    ERROR: Packet Underflow (tests/tests/Echo.cpp:61:35)
Buffer (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:123:5)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Sent 26 bytes to 127.0.0.1:4242. (src/Core/Peer.cpp:27:13)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Received 26 bytes from 127.0.0.1:56981. (src/Core/Peer.cpp:53:13)
2025-12-09 10:48:25.254 [IoThread]    DEBUG: Session 127.0.0.1 connected (tests/tests/Echo.cpp:34:93)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Handling incoming raw data
Size: 26 bytes
Data (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:30:5)
2025-12-09 10:48:25.254 [IoThread]    DEBUG: Server received a message! (tests/tests/Echo.cpp:25:47)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Preparing to send a packet.
Sequence ID: 0
Acknowledge ID: 0
Acknowledge bitfield: 0
Message ID: 1
Flags: 2
Payload Size: 1
Checksum: 0
Buffer (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:123:5)
2025-12-09 10:48:25.254 [IoThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Sent 26 bytes to 127.0.0.1:56981. (src/Core/Peer.cpp:27:13)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Received 26 bytes from 127.0.0.1:4242. (src/Core/Peer.cpp:53:13)
2025-12-09 10:48:25.254 [IoThread] TRACE_R3: Handling incoming raw data
Size: 26 bytes
Data (BE): [FE ED 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 01 02 00 01 00 00 00 00 00] (src/Core/Session.cpp:30:5)
2025-12-09 10:48:25.255 [IoThread]    DEBUG: Listening... (src/Core/Peer.cpp:34:29)
tests/tests/Echo.cpp:80: Failure
Expected equality of these values:
  status
    Which is: 4-byte object <01-00 00-00>
  std::future_status::ready
    Which is: 4-byte object <00-00 00-00>
Test timed out!


2025-12-09 10:48:27.259 [MainThread]     INFO: [basic] Test ended: KO. (tests/Tests.h:55:100)
2025-12-09 10:48:27.259 [MainThread]     INFO: [assert] Starting test. (tests/Tests.h:51:56)
2025-12-09 10:48:27.259 [MainThread]     INFO: [assert] Test ended: OK. (tests/Tests.h:55:100)
2025-12-09 10:48:27.259 [MainThread]     INFO: Shutting down Logger, will dump remaining logs (2). (/Users/ly/Epitech/Year03/CPP/rtype/lib/shuvlog/src/Logger.cpp:165:83)


Process finished with exit code 1

Metadata

Metadata

Assignees

Labels

No labels
No labels

Type

Bug

Projects

No projects

Milestone

No milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions