From aff2f6df6ad9125eb5c3226a5d03bcfc1ff51839 Mon Sep 17 00:00:00 2001 From: Ryan Gang Date: Fri, 10 May 2024 15:11:33 +0530 Subject: [PATCH] fix: add log prefix to pre-handshake logs --- ...receive_replication_handshake_test_case.go | 10 +- internal/test_helpers/fixtures/repl-wait/pass | 230 +++++++++--------- internal/test_helpers/fixtures/streams/pass | 192 +++++++-------- 3 files changed, 216 insertions(+), 216 deletions(-) diff --git a/internal/test_cases/receive_replication_handshake_test_case.go b/internal/test_cases/receive_replication_handshake_test_case.go index 5dbb836..25b6b94 100644 --- a/internal/test_cases/receive_replication_handshake_test_case.go +++ b/internal/test_cases/receive_replication_handshake_test_case.go @@ -44,8 +44,8 @@ func (t ReceiveReplicationHandshakeTestCase) RunAll(client *resp_connection.Resp } func (t ReceiveReplicationHandshakeTestCase) RunPingStep(client *resp_connection.RespConnection, logger *logger.Logger) error { - logger.Debugf("Waiting for replica to initiate Replication handshake.") - logger.Debugf("Waiting for replica to send %q command", "PING") + logger.Debugf("master: Waiting for replica to initiate Replication handshake.") + logger.Debugf("master: Waiting for replica to send %q command", "PING") commandTest := ReceiveCommandTestCase{ Assertion: resp_assertions.NewCommandAssertion("PING"), @@ -56,7 +56,7 @@ func (t ReceiveReplicationHandshakeTestCase) RunPingStep(client *resp_connection } func (t ReceiveReplicationHandshakeTestCase) RunReplconfStep1(client *resp_connection.RespConnection, logger *logger.Logger) error { - logger.Debugf("Waiting for replica to send %q command", "REPLCONF listening-port") + logger.Debugf("master: Waiting for replica to send %q command", "REPLCONF listening-port") commandTest := ReceiveCommandTestCase{ Assertion: resp_assertions.NewCommandAssertion("REPLCONF", "listening-port", "6380"), @@ -68,7 +68,7 @@ func (t ReceiveReplicationHandshakeTestCase) RunReplconfStep1(client *resp_conne } func (t ReceiveReplicationHandshakeTestCase) RunReplconfStep2(client *resp_connection.RespConnection, logger *logger.Logger) error { - logger.Debugf("Waiting for replica to send %q command", "REPLCONF capa") + logger.Debugf("master: Waiting for replica to send %q command", "REPLCONF capa") commandTest := ReceiveCommandTestCase{ Assertion: resp_assertions.NewOnlyCommandAssertion("REPLCONF"), @@ -114,7 +114,7 @@ func (t ReceiveReplicationHandshakeTestCase) RunReplconfStep2(client *resp_conne } func (t ReceiveReplicationHandshakeTestCase) RunPsyncStep(client *resp_connection.RespConnection, logger *logger.Logger) error { - logger.Debugf("Waiting for replica to send %q command", "PSYNC") + logger.Debugf("master: Waiting for replica to send %q command", "PSYNC") id := "75cd7bc10c49047e0d163660f3b90625b1af31dc" commandTest := ReceiveCommandTestCase{ diff --git a/internal/test_helpers/fixtures/repl-wait/pass b/internal/test_helpers/fixtures/repl-wait/pass index 6db0504..8c73d76 100644 --- a/internal/test_helpers/fixtures/repl-wait/pass +++ b/internal/test_helpers/fixtures/repl-wait/pass @@ -21,11 +21,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-1: $ redis-cli PSYNC ? -1 [stage-31] replica-1: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-1: Received bytes: "+FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0\r\n" -[stage-31] replica-1: Received RESP value: "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" -[stage-31] Received "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" +[stage-31] replica-1: Received bytes: "+FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0\r\n" +[stage-31] replica-1: Received RESP value: "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" +[stage-31] Received "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" [stage-31] Reading RDB file... -[stage-31] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe3\xe6=f\xfa\bused-mem\xc2\xd0\r\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(994a745bd5ee87f3ee75c5819343c6cad1de1492\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x83\xae\x82\xae\xcdC\xfb\x9d" +[stage-31] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2K\xe8=f\xfa\bused-mem\xc20\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(e7d5fcb631515e662297e8888111554c01d22fc1\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffg\xc0\xb4%1\xf8\xe3\x13" [stage-31] Received RDB file [stage-31] Creating replica: 2 [stage-31] replica-2: $ redis-cli PING @@ -45,11 +45,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-2: $ redis-cli PSYNC ? -1 [stage-31] replica-2: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-2: Received bytes: "+FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0\r\n" -[stage-31] replica-2: Received RESP value: "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" -[stage-31] Received "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" +[stage-31] replica-2: Received bytes: "+FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0\r\n" +[stage-31] replica-2: Received RESP value: "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" +[stage-31] Received "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" [stage-31] Reading RDB file... -[stage-31] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe3\xe6=f\xfa\bused-mem\xc2`\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(994a745bd5ee87f3ee75c5819343c6cad1de1492\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xfc\xbc?ym\xd4־" +[stage-31] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2K\xe8=f\xfa\bused-mem\xc2\xc0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(e7d5fcb631515e662297e8888111554c01d22fc1\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xce\xd5#\"\xc9U-\xa6" [stage-31] Received RDB file [stage-31] Creating replica: 3 [stage-31] replica-3: $ redis-cli PING @@ -69,11 +69,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-3: $ redis-cli PSYNC ? -1 [stage-31] replica-3: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-3: Received bytes: "+FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0\r\n" -[stage-31] replica-3: Received RESP value: "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" -[stage-31] Received "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" +[stage-31] replica-3: Received bytes: "+FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0\r\n" +[stage-31] replica-3: Received RESP value: "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" +[stage-31] Received "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" [stage-31] Reading RDB file... -[stage-31] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe3\xe6=f\xfa\bused-mem\xc2`@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(994a745bd5ee87f3ee75c5819343c6cad1de1492\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffě\xa71\x84\x1bA\x90" +[stage-31] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2K\xe8=f\xfa\bused-mem\xc2\xc0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(e7d5fcb631515e662297e8888111554c01d22fc1\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xf6\xf2\xbbj \x9a\xba\x88" [stage-31] Received RDB file [stage-31] Creating replica: 4 [stage-31] replica-4: $ redis-cli PING @@ -93,11 +93,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-4: $ redis-cli PSYNC ? -1 [stage-31] replica-4: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-4: Received bytes: "+FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0\r\n" -[stage-31] replica-4: Received RESP value: "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" -[stage-31] Received "FULLRESYNC 994a745bd5ee87f3ee75c5819343c6cad1de1492 0" +[stage-31] replica-4: Received bytes: "+FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0\r\n" +[stage-31] replica-4: Received RESP value: "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" +[stage-31] Received "FULLRESYNC e7d5fcb631515e662297e8888111554c01d22fc1 0" [stage-31] Reading RDB file... -[stage-31] replica-4: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe4\xe6=f\xfa\bused-mem\xc2p\x8b\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(994a745bd5ee87f3ee75c5819343c6cad1de1492\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x1dA\x83\xca\xd48Gu" +[stage-31] replica-4: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2K\xe8=f\xfa\bused-mem\xc2Ћ\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(e7d5fcb631515e662297e8888111554c01d22fc1\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff2\xc7:\x1d6\xe0\xaf " [stage-31] Received RDB file [stage-31] client: $ redis-cli SET foo 123 [stage-31] client: Sent bytes: "*3\r\n$3\r\nSET\r\n$3\r\nfoo\r\n$3\r\n123\r\n" @@ -206,7 +206,7 @@ Debug = true [stage-31] Received ["REPLCONF", "GETACK", "*"] [stage-31] client: Received bytes: ":3\r\n" [stage-31] client: Received RESP value: 3 -[stage-31] WAIT command returned after 2105 ms +[stage-31] WAIT command returned after 2102 ms [stage-31] Test passed. [stage-31] Terminating program [stage-31] Program terminated successfully @@ -232,11 +232,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-1: $ redis-cli PSYNC ? -1 [stage-30] replica-1: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-1: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-1: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-1: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-1: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2P\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xcd\x16\x82\xee?!p\xba" +[stage-30] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2P\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffΚ\xe4\xc6\f\x8f\x99\xd7" [stage-30] Received RDB file [stage-30] Creating replica: 2 [stage-30] replica-2: $ redis-cli PING @@ -256,11 +256,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-2: $ redis-cli PSYNC ? -1 [stage-30] replica-2: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-2: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-2: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-2: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-2: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2\xe0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x86\xe5%\xbeYE\x04\xe3" +[stage-30] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2\xe0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x85iC\x96j\xeb\xed\x8e" [stage-30] Received RDB file [stage-30] Creating replica: 3 [stage-30] replica-3: $ redis-cli PING @@ -280,11 +280,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-3: $ redis-cli PSYNC ? -1 [stage-30] replica-3: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-3: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-3: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-3: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-3: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2\xe0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xbe½\xf6\xb0\x8a\x93\xcd" +[stage-30] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2\xe0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xbdN\xdbރ$z\xa0" [stage-30] Received RDB file [stage-30] Creating replica: 4 [stage-30] replica-4: $ redis-cli PING @@ -304,11 +304,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-4: $ redis-cli PSYNC ? -1 [stage-30] replica-4: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-4: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-4: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-4: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-4: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-4: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2\xf0\x8b\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffz\xf7<\x81\xa6\xf0\x86e" +[stage-30] replica-4: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2\xf0\x8b\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffy{Z\xa9\x95^o\b" [stage-30] Received RDB file [stage-30] Creating replica: 5 [stage-30] replica-5: $ redis-cli PING @@ -328,11 +328,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-5: $ redis-cli PSYNC ? -1 [stage-30] replica-5: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-5: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-5: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-5: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-5: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-5: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2\x00\xd7\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x03\xe6\xc0x\xeep\xc0\xfe" +[stage-30] replica-5: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2\x00\xd7\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x00j\xa6P\xdd\xde)\x93" [stage-30] Received RDB file [stage-30] Creating replica: 6 [stage-30] replica-6: $ redis-cli PING @@ -352,11 +352,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-6: $ redis-cli PSYNC ? -1 [stage-30] replica-6: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-6: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-6: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-6: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-6: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-6: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2\x00\"\x14\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffnS\x01\x91\x04\x91\xc9K" +[stage-30] replica-6: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2\x00\"\x14\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffm\xdfg\xb97? &" [stage-30] Received RDB file [stage-30] Creating replica: 7 [stage-30] replica-7: $ redis-cli PING @@ -376,11 +376,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-7: $ redis-cli PSYNC ? -1 [stage-30] replica-7: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-7: Received bytes: "+FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0\r\n" -[stage-30] replica-7: Received RESP value: "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" -[stage-30] Received "FULLRESYNC c3415e3edeedbdb7d214b75b8456492b06d38012 0" +[stage-30] replica-7: Received bytes: "+FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0\r\n" +[stage-30] replica-7: Received RESP value: "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" +[stage-30] Received "FULLRESYNC 7666edc3011b15821dd8fe28ff048a07c86a0925 0" [stage-30] Reading RDB file... -[stage-30] replica-7: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe6\xe6=f\xfa\bused-mem\xc2\x10m\x14\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(c3415e3edeedbdb7d214b75b8456492b06d38012\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xd0\xce~\xcc\xfb\xbf\xed\xee" +[stage-30] replica-7: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2N\xe8=f\xfa\bused-mem\xc2\x10m\x14\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7666edc3011b15821dd8fe28ff048a07c86a0925\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xd3B\x18\xe4\xc8\x11\x04\x83" [stage-30] Received RDB file [stage-30] client: $ redis-cli WAIT 3 500 [stage-30] client: Sent bytes: "*3\r\n$4\r\nWAIT\r\n$1\r\n3\r\n$3\r\n500\r\n" @@ -420,26 +420,26 @@ Debug = true [stage-28] Running tests for Stage #28: repl-replica-getack-nonzero [stage-28] Master is running on port 6379 [stage-28] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-28] Waiting for replica to initiate Replication handshake. -[stage-28] Waiting for replica to send "PING" command +[stage-28] master: Waiting for replica to initiate Replication handshake. +[stage-28] master: Waiting for replica to send "PING" command [stage-28] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-28] master: Received RESP value: ["PING"] [stage-28] Received ["PING"] [stage-28] master: Sent "PONG" [stage-28] master: Sent bytes: "+PONG\r\n" -[stage-28] Waiting for replica to send "REPLCONF listening-port" command +[stage-28] master: Waiting for replica to send "REPLCONF listening-port" command [stage-28] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-28] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-28] Received ["REPLCONF", "listening-port", "6380"] [stage-28] master: Sent "OK" [stage-28] master: Sent bytes: "+OK\r\n" -[stage-28] Waiting for replica to send "REPLCONF capa" command +[stage-28] master: Waiting for replica to send "REPLCONF capa" command [stage-28] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-28] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-28] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-28] master: Sent "OK" [stage-28] master: Sent bytes: "+OK\r\n" -[stage-28] Waiting for replica to send "PSYNC" command +[stage-28] master: Waiting for replica to send "PSYNC" command [stage-28] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-28] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-28] Received ["PSYNC", "?", "-1"] @@ -476,26 +476,26 @@ Debug = true [stage-27] Running tests for Stage #27: repl-replica-getack [stage-27] Master is running on port 6379 [stage-27] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-27] Waiting for replica to initiate Replication handshake. -[stage-27] Waiting for replica to send "PING" command +[stage-27] master: Waiting for replica to initiate Replication handshake. +[stage-27] master: Waiting for replica to send "PING" command [stage-27] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-27] master: Received RESP value: ["PING"] [stage-27] Received ["PING"] [stage-27] master: Sent "PONG" [stage-27] master: Sent bytes: "+PONG\r\n" -[stage-27] Waiting for replica to send "REPLCONF listening-port" command +[stage-27] master: Waiting for replica to send "REPLCONF listening-port" command [stage-27] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-27] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-27] Received ["REPLCONF", "listening-port", "6380"] [stage-27] master: Sent "OK" [stage-27] master: Sent bytes: "+OK\r\n" -[stage-27] Waiting for replica to send "REPLCONF capa" command +[stage-27] master: Waiting for replica to send "REPLCONF capa" command [stage-27] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-27] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-27] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-27] master: Sent "OK" [stage-27] master: Sent bytes: "+OK\r\n" -[stage-27] Waiting for replica to send "PSYNC" command +[stage-27] master: Waiting for replica to send "PSYNC" command [stage-27] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-27] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-27] Received ["PSYNC", "?", "-1"] @@ -516,26 +516,26 @@ Debug = true [stage-26] Running tests for Stage #26: repl-cmd-processing [stage-26] Master is running on port 6379 [stage-26] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-26] Waiting for replica to initiate Replication handshake. -[stage-26] Waiting for replica to send "PING" command +[stage-26] master: Waiting for replica to initiate Replication handshake. +[stage-26] master: Waiting for replica to send "PING" command [stage-26] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-26] master: Received RESP value: ["PING"] [stage-26] Received ["PING"] [stage-26] master: Sent "PONG" [stage-26] master: Sent bytes: "+PONG\r\n" -[stage-26] Waiting for replica to send "REPLCONF listening-port" command +[stage-26] master: Waiting for replica to send "REPLCONF listening-port" command [stage-26] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-26] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-26] Received ["REPLCONF", "listening-port", "6380"] [stage-26] master: Sent "OK" [stage-26] master: Sent bytes: "+OK\r\n" -[stage-26] Waiting for replica to send "REPLCONF capa" command +[stage-26] master: Waiting for replica to send "REPLCONF capa" command [stage-26] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-26] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-26] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-26] master: Sent "OK" [stage-26] master: Sent bytes: "+OK\r\n" -[stage-26] Waiting for replica to send "PSYNC" command +[stage-26] master: Waiting for replica to send "PSYNC" command [stage-26] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-26] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-26] Received ["PSYNC", "?", "-1"] @@ -592,11 +592,11 @@ Debug = true [stage-25] Received "OK" [stage-25] replica-1: $ redis-cli PSYNC ? -1 [stage-25] replica-1: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-25] replica-1: Received bytes: "+FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0\r\n" -[stage-25] replica-1: Received RESP value: "FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0" -[stage-25] Received "FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0" +[stage-25] replica-1: Received bytes: "+FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0\r\n" +[stage-25] replica-1: Received RESP value: "FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0" +[stage-25] Received "FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0" [stage-25] Reading RDB file... -[stage-25] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe8\xe6=f\xfa\bused-mem\xc2@S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xe8\xf4\xd0\x0ep0BR" +[stage-25] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2P\xe8=f\xfa\bused-mem\xc2\x00S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(a8e372e257af209220d04019a7c7c17efb96337f\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x9a\xd7\x7f_\xa7;x~" [stage-25] Received RDB file [stage-25] Creating replica: 2 [stage-25] replica-2: $ redis-cli PING @@ -616,11 +616,11 @@ Debug = true [stage-25] Received "OK" [stage-25] replica-2: $ redis-cli PSYNC ? -1 [stage-25] replica-2: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-25] replica-2: Received bytes: "+FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0\r\n" -[stage-25] replica-2: Received RESP value: "FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0" -[stage-25] Received "FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0" +[stage-25] replica-2: Received bytes: "+FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0\r\n" +[stage-25] replica-2: Received RESP value: "FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0" +[stage-25] Received "FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0" [stage-25] Reading RDB file... -[stage-25] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe8\xe6=f\xfa\bused-mem°:\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xbag\x1e\xa9\xffsH\xab" +[stage-25] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2P\xe8=f\xfa\bused-mem\xc2p:\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(a8e372e257af209220d04019a7c7c17efb96337f\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffg\x1aG\x0eG\xcd^u" [stage-25] Received RDB file [stage-25] Creating replica: 3 [stage-25] replica-3: $ redis-cli PING @@ -640,11 +640,11 @@ Debug = true [stage-25] Received "OK" [stage-25] replica-3: $ redis-cli PSYNC ? -1 [stage-25] replica-3: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-25] replica-3: Received bytes: "+FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0\r\n" -[stage-25] replica-3: Received RESP value: "FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0" -[stage-25] Received "FULLRESYNC 3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2 0" +[stage-25] replica-3: Received bytes: "+FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0\r\n" +[stage-25] replica-3: Received RESP value: "FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0" +[stage-25] Received "FULLRESYNC a8e372e257af209220d04019a7c7c17efb96337f 0" [stage-25] Reading RDB file... -[stage-25] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe8\xe6=f\xfa\bused-mem\xc2\xc0I\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(3306c4dc7cf91fd6c095ed8fe1f1fa5fc3f242a2\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff}\x93}6\xc8֥#" +[stage-25] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2P\xe8=f\xfa\bused-mem\u0080I\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(a8e372e257af209220d04019a7c7c17efb96337f\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x0f\xb0\xd2g\x1fݟ\x0f" [stage-25] Received RDB file [stage-25] client: $ redis-cli SET foo 123 [stage-25] client: Sent bytes: "*3\r\n$3\r\nSET\r\n$3\r\nfoo\r\n$3\r\n123\r\n" @@ -729,11 +729,11 @@ Debug = true [stage-24] Received "OK" [stage-24] replica: $ redis-cli PSYNC ? -1 [stage-24] replica: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-24] replica: Received bytes: "+FULLRESYNC 5c731d940a0cdf2ec686a19dd9df7bfa96e670d7 0\r\n" -[stage-24] replica: Received RESP value: "FULLRESYNC 5c731d940a0cdf2ec686a19dd9df7bfa96e670d7 0" -[stage-24] Received "FULLRESYNC 5c731d940a0cdf2ec686a19dd9df7bfa96e670d7 0" +[stage-24] replica: Received bytes: "+FULLRESYNC 2f889339ae770903033fb2a09df9ffb28e1f8ade 0\r\n" +[stage-24] replica: Received RESP value: "FULLRESYNC 2f889339ae770903033fb2a09df9ffb28e1f8ade 0" +[stage-24] Received "FULLRESYNC 2f889339ae770903033fb2a09df9ffb28e1f8ade 0" [stage-24] Reading RDB file... -[stage-24] replica: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe8\xe6=f\xfa\bused-mem\xc2@S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(5c731d940a0cdf2ec686a19dd9df7bfa96e670d7\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x86H\xe7\x8c\x7fx\x8b\xcb" +[stage-24] replica: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2P\xe8=f\xfa\bused-mem\xc2\x00S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(2f889339ae770903033fb2a09df9ffb28e1f8ade\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x15\xe0\x8f}k]\x9b\xb2" [stage-24] Received RDB file [stage-24] client: $ redis-cli SET foo 123 [stage-24] client: Sent bytes: "*3\r\n$3\r\nSET\r\n$3\r\nfoo\r\n$3\r\n123\r\n" @@ -788,11 +788,11 @@ Debug = true [stage-23] Received "OK" [stage-23] client: $ redis-cli PSYNC ? -1 [stage-23] client: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-23] client: Received bytes: "+FULLRESYNC 1385c29566b0cea43cce8ada333b94667c821760 0\r\n" -[stage-23] client: Received RESP value: "FULLRESYNC 1385c29566b0cea43cce8ada333b94667c821760 0" -[stage-23] Received "FULLRESYNC 1385c29566b0cea43cce8ada333b94667c821760 0" +[stage-23] client: Received bytes: "+FULLRESYNC dd5f62d982085ce57acc459b221e03792aeabb9c 0\r\n" +[stage-23] client: Received RESP value: "FULLRESYNC dd5f62d982085ce57acc459b221e03792aeabb9c 0" +[stage-23] Received "FULLRESYNC dd5f62d982085ce57acc459b221e03792aeabb9c 0" [stage-23] Reading RDB file... -[stage-23] client: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xe9\xe6=f\xfa\bused-mem\xc2\x10\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(1385c29566b0cea43cce8ada333b94667c821760\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff[\xdd\xc1\x11\xa4\x19\x12\xd4" +[stage-23] client: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2P\xe8=f\xfa\bused-mem\xc20\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(dd5f62d982085ce57acc459b221e03792aeabb9c\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xb3t\xb6'\xfe_v1" [stage-23] Received RDB file [stage-23] Test passed. [stage-23] Terminating program @@ -817,9 +817,9 @@ Debug = true [stage-22] Received "OK" [stage-22] client: $ redis-cli PSYNC ? -1 [stage-22] client: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-22] client: Received bytes: "+FULLRESYNC 991016c6aba7a8e2de5966dea74c249dd9bd378f 0\r\n" -[stage-22] client: Received RESP value: "FULLRESYNC 991016c6aba7a8e2de5966dea74c249dd9bd378f 0" -[stage-22] Received "FULLRESYNC 991016c6aba7a8e2de5966dea74c249dd9bd378f 0" +[stage-22] client: Received bytes: "+FULLRESYNC d4079c5e9d7bf6cadaa844c948dcd6955a2b89a7 0\r\n" +[stage-22] client: Received RESP value: "FULLRESYNC d4079c5e9d7bf6cadaa844c948dcd6955a2b89a7 0" +[stage-22] Received "FULLRESYNC d4079c5e9d7bf6cadaa844c948dcd6955a2b89a7 0" [stage-22] Test passed. [stage-22] Terminating program [stage-22] Program terminated successfully @@ -848,26 +848,26 @@ Debug = true [stage-20] Running tests for Stage #20: repl-replica-psync [stage-20] Master is running on port 6379 [stage-20] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-20] Waiting for replica to initiate Replication handshake. -[stage-20] Waiting for replica to send "PING" command +[stage-20] master: Waiting for replica to initiate Replication handshake. +[stage-20] master: Waiting for replica to send "PING" command [stage-20] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-20] master: Received RESP value: ["PING"] [stage-20] Received ["PING"] [stage-20] master: Sent "PONG" [stage-20] master: Sent bytes: "+PONG\r\n" -[stage-20] Waiting for replica to send "REPLCONF listening-port" command +[stage-20] master: Waiting for replica to send "REPLCONF listening-port" command [stage-20] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-20] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-20] Received ["REPLCONF", "listening-port", "6380"] [stage-20] master: Sent "OK" [stage-20] master: Sent bytes: "+OK\r\n" -[stage-20] Waiting for replica to send "REPLCONF capa" command +[stage-20] master: Waiting for replica to send "REPLCONF capa" command [stage-20] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-20] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-20] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-20] master: Sent "OK" [stage-20] master: Sent bytes: "+OK\r\n" -[stage-20] Waiting for replica to send "PSYNC" command +[stage-20] master: Waiting for replica to send "PSYNC" command [stage-20] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-20] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-20] Received ["PSYNC", "?", "-1"] @@ -880,20 +880,20 @@ Debug = true [stage-19] Running tests for Stage #19: repl-replica-replconf [stage-19] Master is running on port 6379 [stage-19] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-19] Waiting for replica to initiate Replication handshake. -[stage-19] Waiting for replica to send "PING" command +[stage-19] master: Waiting for replica to initiate Replication handshake. +[stage-19] master: Waiting for replica to send "PING" command [stage-19] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-19] master: Received RESP value: ["PING"] [stage-19] Received ["PING"] [stage-19] master: Sent "PONG" [stage-19] master: Sent bytes: "+PONG\r\n" -[stage-19] Waiting for replica to send "REPLCONF listening-port" command +[stage-19] master: Waiting for replica to send "REPLCONF listening-port" command [stage-19] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-19] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-19] Received ["REPLCONF", "listening-port", "6380"] [stage-19] master: Sent "OK" [stage-19] master: Sent bytes: "+OK\r\n" -[stage-19] Waiting for replica to send "REPLCONF capa" command +[stage-19] master: Waiting for replica to send "REPLCONF capa" command [stage-19] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-19] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-19] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] @@ -906,8 +906,8 @@ Debug = true [stage-18] Running tests for Stage #18: repl-replica-ping [stage-18] Master is running on port 6379. [stage-18] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-18] Waiting for replica to initiate Replication handshake. -[stage-18] Waiting for replica to send "PING" command +[stage-18] master: Waiting for replica to initiate Replication handshake. +[stage-18] master: Waiting for replica to send "PING" command [stage-18] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-18] master: Received RESP value: ["PING"] [stage-18] Received ["PING"] @@ -921,9 +921,9 @@ Debug = true [stage-17] $ ./spawn_redis_server.sh [stage-17] client: $ redis-cli INFO replication [stage-17] client: Sent bytes: "*2\r\n$4\r\nINFO\r\n$11\r\nreplication\r\n" -[stage-17] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:8b3fde66f246b08d91a46a09fbbd6317c78ff9ae\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" -[stage-17] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:8b3fde66f246b08d91a46a09fbbd6317c78ff9ae\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" -[stage-17] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:8b3fde66f246b08d91a46a09fbbd6317c78ff9ae\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-17] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:9577dab66fadc550bc4a7c6935cb88db35e9561a\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" +[stage-17] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:9577dab66fadc550bc4a7c6935cb88db35e9561a\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-17] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:9577dab66fadc550bc4a7c6935cb88db35e9561a\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" [stage-17] Found master_replid:xxx in response. [stage-17] Found master_reploffset:0 in response. [stage-17] Test passed. @@ -964,9 +964,9 @@ Debug = true [stage-15] $ ./spawn_redis_server.sh [stage-15] client: $ redis-cli INFO replication [stage-15] client: Sent bytes: "*2\r\n$4\r\nINFO\r\n$11\r\nreplication\r\n" -[stage-15] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:5c250e06a1a44057c79f181916b0ac050913e8a5\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" -[stage-15] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:5c250e06a1a44057c79f181916b0ac050913e8a5\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" -[stage-15] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:5c250e06a1a44057c79f181916b0ac050913e8a5\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-15] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:eadd11312ed85caa1d7cb02c9322a1f742c98152\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" +[stage-15] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:eadd11312ed85caa1d7cb02c9322a1f742c98152\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-15] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:eadd11312ed85caa1d7cb02c9322a1f742c98152\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" [stage-15] Found role:master in response. [stage-15] Test passed. [stage-15] Terminating program @@ -981,7 +981,7 @@ Debug = true [stage-14] Program terminated successfully [stage-13] Running tests for Stage #13: rdb-read-value-with-expiry -[stage-13] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3104995577 --dbfilename pear.rdb +[stage-13] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles90396761 --dbfilename pear.rdb [stage-13] client: $ redis-cli GET blueberry [stage-13] client: Sent bytes: "*2\r\n$3\r\nGET\r\n$9\r\nblueberry\r\n" [stage-13] client: Received bytes: "$9\r\nblueberry\r\n" @@ -1003,7 +1003,7 @@ Debug = true [stage-12] Running tests for Stage #12: rdb-read-multiple-string-values [stage-12] Created RDB file with key-value pairs: "grape"="mango", "orange"="banana", "blueberry"="orange", "pear"="grape", "banana"="blueberry" -[stage-12] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3528887592 --dbfilename mango.rdb +[stage-12] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1153302028 --dbfilename mango.rdb [stage-12] client: $ redis-cli GET grape [stage-12] client: Sent bytes: "*2\r\n$3\r\nGET\r\n$5\r\ngrape\r\n" [stage-12] client: Received bytes: "$5\r\nmango\r\n" @@ -1035,19 +1035,19 @@ Debug = true [stage-11] Running tests for Stage #11: rdb-read-multiple-keys [stage-11] Created RDB file with 4 keys: ["raspberry" "blueberry" "apple" "orange"] -[stage-11] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2064530156 --dbfilename grape.rdb +[stage-11] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2792794160 --dbfilename grape.rdb [stage-11] client: $ redis-cli KEYS * [stage-11] client: Sent bytes: "*2\r\n$4\r\nKEYS\r\n$1\r\n*\r\n" -[stage-11] client: Received bytes: "*4\r\n$6\r\norange\r\n$9\r\nraspberry\r\n$5\r\napple\r\n$9\r\nblueberry\r\n" -[stage-11] client: Received RESP value: ["orange", "raspberry", "apple", "blueberry"] -[stage-11] Received ["orange", "raspberry", "apple", "blueberry"] +[stage-11] client: Received bytes: "*4\r\n$9\r\nraspberry\r\n$9\r\nblueberry\r\n$6\r\norange\r\n$5\r\napple\r\n" +[stage-11] client: Received RESP value: ["raspberry", "blueberry", "orange", "apple"] +[stage-11] Received ["raspberry", "blueberry", "orange", "apple"] [stage-11] Test passed. [stage-11] Terminating program [stage-11] Program terminated successfully [stage-10] Running tests for Stage #10: rdb-read-string-value [stage-10] Created RDB file with single key-value pair: blueberry="raspberry" -[stage-10] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3745908572 --dbfilename strawberry.rdb +[stage-10] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2781943397 --dbfilename strawberry.rdb [stage-10] client: $ redis-cli GET blueberry [stage-10] client: Sent bytes: "*2\r\n$3\r\nGET\r\n$9\r\nblueberry\r\n" [stage-10] client: Received bytes: "$9\r\nraspberry\r\n" @@ -1059,7 +1059,7 @@ Debug = true [stage-9] Running tests for Stage #9: rdb-read-key [stage-9] Created RDB file with single key: "grape" -[stage-9] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles641404387 --dbfilename orange.rdb +[stage-9] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1142827874 --dbfilename orange.rdb [stage-9] client: $ redis-cli KEYS * [stage-9] client: Sent bytes: "*2\r\n$4\r\nKEYS\r\n$1\r\n*\r\n" [stage-9] client: Received bytes: "*1\r\n$5\r\ngrape\r\n" @@ -1070,12 +1070,12 @@ Debug = true [stage-9] Program terminated successfully [stage-8] Running tests for Stage #8: rdb-config -[stage-8] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2502039932 --dbfilename raspberry.rdb +[stage-8] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3185798488 --dbfilename raspberry.rdb [stage-8] client: $ redis-cli CONFIG GET dir [stage-8] client: Sent bytes: "*3\r\n$6\r\nCONFIG\r\n$3\r\nGET\r\n$3\r\ndir\r\n" -[stage-8] client: Received bytes: "*2\r\n$3\r\ndir\r\n$75\r\n/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2502039932\r\n" -[stage-8] client: Received RESP value: ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2502039932"] -[stage-8] Received ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2502039932"] +[stage-8] client: Received bytes: "*2\r\n$3\r\ndir\r\n$75\r\n/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3185798488\r\n" +[stage-8] client: Received RESP value: ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3185798488"] +[stage-8] Received ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3185798488"] [stage-8] Test passed. [stage-8] Terminating program [stage-8] Program terminated successfully @@ -1087,15 +1087,15 @@ Debug = true [stage-7] Received bytes: "+OK\r\n" [stage-7] Received RESP value: "OK" [stage-7] Received "OK" -[stage-7] Received OK at 14:50:44.237 -[stage-7] Fetching key "mango" at 14:50:44.237 (should not be expired) +[stage-7] Received OK at 14:56:43.941 +[stage-7] Fetching key "mango" at 14:56:43.941 (should not be expired) [stage-7] $ redis-cli GET mango [stage-7] Sent bytes: "*2\r\n$3\r\nGET\r\n$5\r\nmango\r\n" [stage-7] Received bytes: "$6\r\norange\r\n" [stage-7] Received RESP value: "orange" [stage-7] Received "orange" [stage-7] Sleeping for 101ms -[stage-7] Fetching key "mango" at 14:50:44.340 (should be expired) +[stage-7] Fetching key "mango" at 14:56:44.044 (should be expired) [stage-7] $ redis-cli GET mango [stage-7] Sent bytes: "*2\r\n$3\r\nGET\r\n$5\r\nmango\r\n" [stage-7] Received bytes: "$-1\r\n" diff --git a/internal/test_helpers/fixtures/streams/pass b/internal/test_helpers/fixtures/streams/pass index c243277..c73c061 100644 --- a/internal/test_helpers/fixtures/streams/pass +++ b/internal/test_helpers/fixtures/streams/pass @@ -215,7 +215,7 @@ Debug = true [stage-36] Running tests for Stage #36: streams-xadd-full-autoid [stage-36] $ ./spawn_redis_server.sh [stage-36] $ redis-cli xadd "mango" * foo bar -[stage-36] Received response: ""1715332822043-0"" +[stage-36] Received response: ""1715333230412-0"" [stage-36] The first part of the ID is a valid unix milliseconds timestamp [stage-36] The second part of the ID is a valid sequence number [stage-36] Test passed. @@ -293,11 +293,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-1: $ redis-cli PSYNC ? -1 [stage-31] replica-1: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-1: Received bytes: "+FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0\r\n" -[stage-31] replica-1: Received RESP value: "FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0" -[stage-31] Received "FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0" +[stage-31] replica-1: Received bytes: "+FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0\r\n" +[stage-31] replica-1: Received RESP value: "FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0" +[stage-31] Received "FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0" [stage-31] Reading RDB file... -[stage-31] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xd6\xe6=f\xfa\bused-mem\xc20\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(b2f2e605b56c282e87e0dd7e460dc92fc6bfc108\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x86g\xd7\"\x1b\xb4}e" +[stage-31] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2o\xe8=f\xfa\bused-mem\xc2P\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(4e1b02070e7234689b06833678dbe4babb924bb8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffM\xa2仐B\xe9K" [stage-31] Received RDB file [stage-31] Creating replica: 2 [stage-31] replica-2: $ redis-cli PING @@ -317,11 +317,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-2: $ redis-cli PSYNC ? -1 [stage-31] replica-2: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-2: Received bytes: "+FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0\r\n" -[stage-31] replica-2: Received RESP value: "FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0" -[stage-31] Received "FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0" +[stage-31] replica-2: Received bytes: "+FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0\r\n" +[stage-31] replica-2: Received RESP value: "FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0" +[stage-31] Received "FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0" [stage-31] Reading RDB file... -[stage-31] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xd6\xe6=f\xfa\bused-mem\xc2\xc0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(b2f2e605b56c282e87e0dd7e460dc92fc6bfc108\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff/r@%\xe3\x19\xb3\xd0" +[stage-31] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2o\xe8=f\xfa\bused-mem\xc2\xe0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(4e1b02070e7234689b06833678dbe4babb924bb8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x06QC\xeb\xf6&\x9d\x12" [stage-31] Received RDB file [stage-31] Creating replica: 3 [stage-31] replica-3: $ redis-cli PING @@ -341,11 +341,11 @@ Debug = true [stage-31] Received "OK" [stage-31] replica-3: $ redis-cli PSYNC ? -1 [stage-31] replica-3: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-31] replica-3: Received bytes: "+FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0\r\n" -[stage-31] replica-3: Received RESP value: "FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0" -[stage-31] Received "FULLRESYNC b2f2e605b56c282e87e0dd7e460dc92fc6bfc108 0" +[stage-31] replica-3: Received bytes: "+FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0\r\n" +[stage-31] replica-3: Received RESP value: "FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0" +[stage-31] Received "FULLRESYNC 4e1b02070e7234689b06833678dbe4babb924bb8 0" [stage-31] Reading RDB file... -[stage-31] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xd6\xe6=f\xfa\bused-mem\xc2\xc0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(b2f2e605b56c282e87e0dd7e460dc92fc6bfc108\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x17U\xd8m\n\xd6$\xfe" +[stage-31] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2o\xe8=f\xfa\bused-mem\xc2\xe0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(4e1b02070e7234689b06833678dbe4babb924bb8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff>vۣ\x1f\xe9\n<" [stage-31] Received RDB file [stage-31] client: $ redis-cli SET foo 123 [stage-31] client: Sent bytes: "*3\r\n$3\r\nSET\r\n$3\r\nfoo\r\n$3\r\n123\r\n" @@ -432,7 +432,7 @@ Debug = true [stage-31] Received ["REPLCONF", "GETACK", "*"] [stage-31] client: Received bytes: ":2\r\n" [stage-31] client: Received RESP value: 2 -[stage-31] WAIT command returned after 2105 ms +[stage-31] WAIT command returned after 2090 ms [stage-31] Test passed. [stage-31] Terminating program [stage-31] Program terminated successfully @@ -458,11 +458,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-1: $ redis-cli PSYNC ? -1 [stage-30] replica-1: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-1: Received bytes: "+FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0\r\n" -[stage-30] replica-1: Received RESP value: "FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0" -[stage-30] Received "FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0" +[stage-30] replica-1: Received bytes: "+FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0\r\n" +[stage-30] replica-1: Received RESP value: "FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0" +[stage-30] Received "FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0" [stage-30] Reading RDB file... -[stage-30] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xd9\xe6=f\xfa\bused-mem\xc2P\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xca\xc6\xce\x06\xa9.Q\xc5" +[stage-30] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2q\xe8=f\xfa\bused-mem\xc2\x10\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xd41?\xeetq\x03a" [stage-30] Received RDB file [stage-30] Creating replica: 2 [stage-30] replica-2: $ redis-cli PING @@ -482,11 +482,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-2: $ redis-cli PSYNC ? -1 [stage-30] replica-2: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-2: Received bytes: "+FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0\r\n" -[stage-30] replica-2: Received RESP value: "FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0" -[stage-30] Received "FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0" +[stage-30] replica-2: Received bytes: "+FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0\r\n" +[stage-30] replica-2: Received RESP value: "FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0" +[stage-30] Received "FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0" [stage-30] Reading RDB file... -[stage-30] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xd9\xe6=f\xfa\bused-mem\xc2\xe0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x815iV\xcfJ%\x9c" +[stage-30] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2q\xe8=f\xfa\bused-mem\u00a0\xf5\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x9f\u0098\xbe\x12\x15w8" [stage-30] Received RDB file [stage-30] Creating replica: 3 [stage-30] replica-3: $ redis-cli PING @@ -506,11 +506,11 @@ Debug = true [stage-30] Received "OK" [stage-30] replica-3: $ redis-cli PSYNC ? -1 [stage-30] replica-3: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-30] replica-3: Received bytes: "+FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0\r\n" -[stage-30] replica-3: Received RESP value: "FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0" -[stage-30] Received "FULLRESYNC 8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8 0" +[stage-30] replica-3: Received bytes: "+FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0\r\n" +[stage-30] replica-3: Received RESP value: "FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0" +[stage-30] Received "FULLRESYNC ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71 0" [stage-30] Reading RDB file... -[stage-30] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xd9\xe6=f\xfa\bused-mem\xc2\xe0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(8089ac90a29d34c3eafb5443a5c1ec6a8baf2cd8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xb9\x12\xf1\x1e&\x85\xb2\xb2" +[stage-30] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2q\xe8=f\xfa\bused-mem\u00a0@\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(ac2b067c63eb8d5c47c0b4e24fd3f8d4ec438d71\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xa7\xe5\x00\xf6\xfb\xda\xe0\x16" [stage-30] Received RDB file [stage-30] client: $ redis-cli WAIT 3 500 [stage-30] client: Sent bytes: "*3\r\n$4\r\nWAIT\r\n$1\r\n3\r\n$3\r\n500\r\n" @@ -545,26 +545,26 @@ Debug = true [stage-28] Running tests for Stage #28: repl-replica-getack-nonzero [stage-28] Master is running on port 6379 [stage-28] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-28] Waiting for replica to initiate Replication handshake. -[stage-28] Waiting for replica to send "PING" command +[stage-28] master: Waiting for replica to initiate Replication handshake. +[stage-28] master: Waiting for replica to send "PING" command [stage-28] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-28] master: Received RESP value: ["PING"] [stage-28] Received ["PING"] [stage-28] master: Sent "PONG" [stage-28] master: Sent bytes: "+PONG\r\n" -[stage-28] Waiting for replica to send "REPLCONF listening-port" command +[stage-28] master: Waiting for replica to send "REPLCONF listening-port" command [stage-28] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-28] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-28] Received ["REPLCONF", "listening-port", "6380"] [stage-28] master: Sent "OK" [stage-28] master: Sent bytes: "+OK\r\n" -[stage-28] Waiting for replica to send "REPLCONF capa" command +[stage-28] master: Waiting for replica to send "REPLCONF capa" command [stage-28] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-28] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-28] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-28] master: Sent "OK" [stage-28] master: Sent bytes: "+OK\r\n" -[stage-28] Waiting for replica to send "PSYNC" command +[stage-28] master: Waiting for replica to send "PSYNC" command [stage-28] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-28] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-28] Received ["PSYNC", "?", "-1"] @@ -601,26 +601,26 @@ Debug = true [stage-27] Running tests for Stage #27: repl-replica-getack [stage-27] Master is running on port 6379 [stage-27] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-27] Waiting for replica to initiate Replication handshake. -[stage-27] Waiting for replica to send "PING" command +[stage-27] master: Waiting for replica to initiate Replication handshake. +[stage-27] master: Waiting for replica to send "PING" command [stage-27] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-27] master: Received RESP value: ["PING"] [stage-27] Received ["PING"] [stage-27] master: Sent "PONG" [stage-27] master: Sent bytes: "+PONG\r\n" -[stage-27] Waiting for replica to send "REPLCONF listening-port" command +[stage-27] master: Waiting for replica to send "REPLCONF listening-port" command [stage-27] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-27] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-27] Received ["REPLCONF", "listening-port", "6380"] [stage-27] master: Sent "OK" [stage-27] master: Sent bytes: "+OK\r\n" -[stage-27] Waiting for replica to send "REPLCONF capa" command +[stage-27] master: Waiting for replica to send "REPLCONF capa" command [stage-27] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-27] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-27] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-27] master: Sent "OK" [stage-27] master: Sent bytes: "+OK\r\n" -[stage-27] Waiting for replica to send "PSYNC" command +[stage-27] master: Waiting for replica to send "PSYNC" command [stage-27] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-27] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-27] Received ["PSYNC", "?", "-1"] @@ -641,26 +641,26 @@ Debug = true [stage-26] Running tests for Stage #26: repl-cmd-processing [stage-26] Master is running on port 6379 [stage-26] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-26] Waiting for replica to initiate Replication handshake. -[stage-26] Waiting for replica to send "PING" command +[stage-26] master: Waiting for replica to initiate Replication handshake. +[stage-26] master: Waiting for replica to send "PING" command [stage-26] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-26] master: Received RESP value: ["PING"] [stage-26] Received ["PING"] [stage-26] master: Sent "PONG" [stage-26] master: Sent bytes: "+PONG\r\n" -[stage-26] Waiting for replica to send "REPLCONF listening-port" command +[stage-26] master: Waiting for replica to send "REPLCONF listening-port" command [stage-26] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-26] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-26] Received ["REPLCONF", "listening-port", "6380"] [stage-26] master: Sent "OK" [stage-26] master: Sent bytes: "+OK\r\n" -[stage-26] Waiting for replica to send "REPLCONF capa" command +[stage-26] master: Waiting for replica to send "REPLCONF capa" command [stage-26] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-26] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-26] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-26] master: Sent "OK" [stage-26] master: Sent bytes: "+OK\r\n" -[stage-26] Waiting for replica to send "PSYNC" command +[stage-26] master: Waiting for replica to send "PSYNC" command [stage-26] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-26] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-26] Received ["PSYNC", "?", "-1"] @@ -717,11 +717,11 @@ Debug = true [stage-25] Received "OK" [stage-25] replica-1: $ redis-cli PSYNC ? -1 [stage-25] replica-1: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-25] replica-1: Received bytes: "+FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0\r\n" -[stage-25] replica-1: Received RESP value: "FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0" -[stage-25] Received "FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0" +[stage-25] replica-1: Received bytes: "+FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0\r\n" +[stage-25] replica-1: Received RESP value: "FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0" +[stage-25] Received "FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0" [stage-25] Reading RDB file... -[stage-25] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xdb\xe6=f\xfa\bused-mem\u0080S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(dd89183d3632c095b6344fd1f91e6c340c86315b\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffy'\xb4\x9bm\x1cZ\xb1" +[stage-25] replica-1: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2s\xe8=f\xfa\bused-mem\xc2`S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(73b89cc03a2b751b1d750226da232518a9b968f2\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xc8\xe3\x1d\xf6\xf6\xc7\xea\x02" [stage-25] Received RDB file [stage-25] Creating replica: 2 [stage-25] replica-2: $ redis-cli PING @@ -741,11 +741,11 @@ Debug = true [stage-25] Received "OK" [stage-25] replica-2: $ redis-cli PSYNC ? -1 [stage-25] replica-2: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-25] replica-2: Received bytes: "+FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0\r\n" -[stage-25] replica-2: Received RESP value: "FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0" -[stage-25] Received "FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0" +[stage-25] replica-2: Received bytes: "+FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0\r\n" +[stage-25] replica-2: Received RESP value: "FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0" +[stage-25] Received "FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0" [stage-25] Reading RDB file... -[stage-25] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xdb\xe6=f\xfa\bused-mem\xc2\xf0:\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(dd89183d3632c095b6344fd1f91e6c340c86315b\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x84\xea\x8cʍ\xea|\xba" +[stage-25] replica-2: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2s\xe8=f\xfa\bused-mem\xc2\xd0:\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(73b89cc03a2b751b1d750226da232518a9b968f2\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffx\x96\xe3\x06\xe7MZ\x17" [stage-25] Received RDB file [stage-25] Creating replica: 3 [stage-25] replica-3: $ redis-cli PING @@ -765,11 +765,11 @@ Debug = true [stage-25] Received "OK" [stage-25] replica-3: $ redis-cli PSYNC ? -1 [stage-25] replica-3: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-25] replica-3: Received bytes: "+FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0\r\n" -[stage-25] replica-3: Received RESP value: "FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0" -[stage-25] Received "FULLRESYNC dd89183d3632c095b6344fd1f91e6c340c86315b 0" +[stage-25] replica-3: Received bytes: "+FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0\r\n" +[stage-25] replica-3: Received RESP value: "FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0" +[stage-25] Received "FULLRESYNC 73b89cc03a2b751b1d750226da232518a9b968f2 0" [stage-25] Reading RDB file... -[stage-25] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xdb\xe6=f\xfa\bused-mem\xc2\x00J\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(dd89183d3632c095b6344fd1f91e6c340c86315b\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffء\x03$\x13\t\xe4\xba" +[stage-25] replica-3: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2s\xe8=f\xfa\bused-mem\xc2\xe0I\x13\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(73b89cc03a2b751b1d750226da232518a9b968f2\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff]\x84\xb0\xceN!\rs" [stage-25] Received RDB file [stage-25] client: $ redis-cli SET foo 123 [stage-25] client: Sent bytes: "*3\r\n$3\r\nSET\r\n$3\r\nfoo\r\n$3\r\n123\r\n" @@ -854,11 +854,11 @@ Debug = true [stage-24] Received "OK" [stage-24] replica: $ redis-cli PSYNC ? -1 [stage-24] replica: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-24] replica: Received bytes: "+FULLRESYNC 594bb3d4d0c35ed4d7929ccb95eb576e52302361 0\r\n" -[stage-24] replica: Received RESP value: "FULLRESYNC 594bb3d4d0c35ed4d7929ccb95eb576e52302361 0" -[stage-24] Received "FULLRESYNC 594bb3d4d0c35ed4d7929ccb95eb576e52302361 0" +[stage-24] replica: Received bytes: "+FULLRESYNC f69111665bd4045d761425529d89b7eee82193c8 0\r\n" +[stage-24] replica: Received RESP value: "FULLRESYNC f69111665bd4045d761425529d89b7eee82193c8 0" +[stage-24] Received "FULLRESYNC f69111665bd4045d761425529d89b7eee82193c8 0" [stage-24] Reading RDB file... -[stage-24] replica: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xdb\xe6=f\xfa\bused-mem\xc2`S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(594bb3d4d0c35ed4d7929ccb95eb576e52302361\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\x0e\xf1E\xe6x~\xf4\xf7" +[stage-24] replica: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2t\xe8=f\xfa\bused-mem\xc2@S\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(f69111665bd4045d761425529d89b7eee82193c8\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xffK_\x83͏\x15\x94\x18" [stage-24] Received RDB file [stage-24] client: $ redis-cli SET foo 123 [stage-24] client: Sent bytes: "*3\r\n$3\r\nSET\r\n$3\r\nfoo\r\n$3\r\n123\r\n" @@ -913,11 +913,11 @@ Debug = true [stage-23] Received "OK" [stage-23] client: $ redis-cli PSYNC ? -1 [stage-23] client: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-23] client: Received bytes: "+FULLRESYNC 7cfb6349b7168b538da23e990545a59c2e2dc602 0\r\n" -[stage-23] client: Received RESP value: "FULLRESYNC 7cfb6349b7168b538da23e990545a59c2e2dc602 0" -[stage-23] Received "FULLRESYNC 7cfb6349b7168b538da23e990545a59c2e2dc602 0" +[stage-23] client: Received bytes: "+FULLRESYNC 101455c70e8190cd6854679a055443a876c9c51a 0\r\n" +[stage-23] client: Received RESP value: "FULLRESYNC 101455c70e8190cd6854679a055443a876c9c51a 0" +[stage-23] Received "FULLRESYNC 101455c70e8190cd6854679a055443a876c9c51a 0" [stage-23] Reading RDB file... -[stage-23] client: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2\xdc\xe6=f\xfa\bused-mem\xc20\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(7cfb6349b7168b538da23e990545a59c2e2dc602\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff_g]3V\xd9lY" +[stage-23] client: Received bytes: "$171\r\nREDIS0011\xfa\tredis-ver\x057.2.4\xfa\nredis-bits\xc0@\xfa\x05ctime\xc2t\xe8=f\xfa\bused-mem\xc2P\x0e\x12\x00\xfa\x0erepl-stream-db\xc0\x00\xfa\arepl-id(101455c70e8190cd6854679a055443a876c9c51a\xfa\vrepl-offset\xc0\x00\xfa\baof-base\xc0\x00\xff\xe5\x80\xc5'n&j\xc8" [stage-23] Received RDB file [stage-23] Test passed. [stage-23] Terminating program @@ -942,9 +942,9 @@ Debug = true [stage-22] Received "OK" [stage-22] client: $ redis-cli PSYNC ? -1 [stage-22] client: Sent bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" -[stage-22] client: Received bytes: "+FULLRESYNC cf23a1a02d507ccd4f62ef5422bdc7a0120da40d 0\r\n" -[stage-22] client: Received RESP value: "FULLRESYNC cf23a1a02d507ccd4f62ef5422bdc7a0120da40d 0" -[stage-22] Received "FULLRESYNC cf23a1a02d507ccd4f62ef5422bdc7a0120da40d 0" +[stage-22] client: Received bytes: "+FULLRESYNC abbabff498909dee962471bb3fb4d3d9b199d2ef 0\r\n" +[stage-22] client: Received RESP value: "FULLRESYNC abbabff498909dee962471bb3fb4d3d9b199d2ef 0" +[stage-22] Received "FULLRESYNC abbabff498909dee962471bb3fb4d3d9b199d2ef 0" [stage-22] Test passed. [stage-22] Terminating program [stage-22] Program terminated successfully @@ -973,26 +973,26 @@ Debug = true [stage-20] Running tests for Stage #20: repl-replica-psync [stage-20] Master is running on port 6379 [stage-20] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-20] Waiting for replica to initiate Replication handshake. -[stage-20] Waiting for replica to send "PING" command +[stage-20] master: Waiting for replica to initiate Replication handshake. +[stage-20] master: Waiting for replica to send "PING" command [stage-20] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-20] master: Received RESP value: ["PING"] [stage-20] Received ["PING"] [stage-20] master: Sent "PONG" [stage-20] master: Sent bytes: "+PONG\r\n" -[stage-20] Waiting for replica to send "REPLCONF listening-port" command +[stage-20] master: Waiting for replica to send "REPLCONF listening-port" command [stage-20] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-20] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-20] Received ["REPLCONF", "listening-port", "6380"] [stage-20] master: Sent "OK" [stage-20] master: Sent bytes: "+OK\r\n" -[stage-20] Waiting for replica to send "REPLCONF capa" command +[stage-20] master: Waiting for replica to send "REPLCONF capa" command [stage-20] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-20] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-20] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-20] master: Sent "OK" [stage-20] master: Sent bytes: "+OK\r\n" -[stage-20] Waiting for replica to send "PSYNC" command +[stage-20] master: Waiting for replica to send "PSYNC" command [stage-20] master: Received bytes: "*3\r\n$5\r\nPSYNC\r\n$1\r\n?\r\n$2\r\n-1\r\n" [stage-20] master: Received RESP value: ["PSYNC", "?", "-1"] [stage-20] Received ["PSYNC", "?", "-1"] @@ -1005,20 +1005,20 @@ Debug = true [stage-19] Running tests for Stage #19: repl-replica-replconf [stage-19] Master is running on port 6379 [stage-19] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-19] Waiting for replica to initiate Replication handshake. -[stage-19] Waiting for replica to send "PING" command +[stage-19] master: Waiting for replica to initiate Replication handshake. +[stage-19] master: Waiting for replica to send "PING" command [stage-19] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-19] master: Received RESP value: ["PING"] [stage-19] Received ["PING"] [stage-19] master: Sent "PONG" [stage-19] master: Sent bytes: "+PONG\r\n" -[stage-19] Waiting for replica to send "REPLCONF listening-port" command +[stage-19] master: Waiting for replica to send "REPLCONF listening-port" command [stage-19] master: Received bytes: "*3\r\n$8\r\nREPLCONF\r\n$14\r\nlistening-port\r\n$4\r\n6380\r\n" [stage-19] master: Received RESP value: ["REPLCONF", "listening-port", "6380"] [stage-19] Received ["REPLCONF", "listening-port", "6380"] [stage-19] master: Sent "OK" [stage-19] master: Sent bytes: "+OK\r\n" -[stage-19] Waiting for replica to send "REPLCONF capa" command +[stage-19] master: Waiting for replica to send "REPLCONF capa" command [stage-19] master: Received bytes: "*5\r\n$8\r\nREPLCONF\r\n$4\r\ncapa\r\n$3\r\neof\r\n$4\r\ncapa\r\n$6\r\npsync2\r\n" [stage-19] master: Received RESP value: ["REPLCONF", "capa", "eof", "capa", "psync2"] [stage-19] Received ["REPLCONF", "capa", "eof", "capa", "psync2"] @@ -1031,8 +1031,8 @@ Debug = true [stage-18] Running tests for Stage #18: repl-replica-ping [stage-18] Master is running on port 6379. [stage-18] $ ./spawn_redis_server.sh --port 6380 --replicaof localhost 6379 -[stage-18] Waiting for replica to initiate Replication handshake. -[stage-18] Waiting for replica to send "PING" command +[stage-18] master: Waiting for replica to initiate Replication handshake. +[stage-18] master: Waiting for replica to send "PING" command [stage-18] master: Received bytes: "*1\r\n$4\r\nPING\r\n" [stage-18] master: Received RESP value: ["PING"] [stage-18] Received ["PING"] @@ -1046,9 +1046,9 @@ Debug = true [stage-17] $ ./spawn_redis_server.sh [stage-17] client: $ redis-cli INFO replication [stage-17] client: Sent bytes: "*2\r\n$4\r\nINFO\r\n$11\r\nreplication\r\n" -[stage-17] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:c8507c749dc632bcaee3cec99a0d4504ce1961dd\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" -[stage-17] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:c8507c749dc632bcaee3cec99a0d4504ce1961dd\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" -[stage-17] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:c8507c749dc632bcaee3cec99a0d4504ce1961dd\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-17] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:61b8d8816e153d72e13825a2209e4cbdd2a68d01\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" +[stage-17] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:61b8d8816e153d72e13825a2209e4cbdd2a68d01\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-17] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:61b8d8816e153d72e13825a2209e4cbdd2a68d01\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" [stage-17] Found master_replid:xxx in response. [stage-17] Found master_reploffset:0 in response. [stage-17] Test passed. @@ -1089,9 +1089,9 @@ Debug = true [stage-15] $ ./spawn_redis_server.sh [stage-15] client: $ redis-cli INFO replication [stage-15] client: Sent bytes: "*2\r\n$4\r\nINFO\r\n$11\r\nreplication\r\n" -[stage-15] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:f4dfca3d2a77dcb3174c002e03ecc42519f7cdb7\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" -[stage-15] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:f4dfca3d2a77dcb3174c002e03ecc42519f7cdb7\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" -[stage-15] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:f4dfca3d2a77dcb3174c002e03ecc42519f7cdb7\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-15] client: Received bytes: "$349\r\n# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:d912d79c6b34ccd8f8eba2f097f41ff9c5f3d68f\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n\r\n" +[stage-15] client: Received RESP value: "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:d912d79c6b34ccd8f8eba2f097f41ff9c5f3d68f\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" +[stage-15] Received "# Replication\r\nrole:master\r\nconnected_slaves:0\r\nmaster_failover_state:no-failover\r\nmaster_replid:d912d79c6b34ccd8f8eba2f097f41ff9c5f3d68f\r\nmaster_replid2:0000000000000000000000000000000000000000\r\nmaster_repl_offset:0\r\nsecond_repl_offset:-1\r\nrepl_backlog_active:0\r\nrepl_backlog_size:1048576\r\nrepl_backlog_first_byte_offset:0\r\nrepl_backlog_histlen:0\r\n" [stage-15] Found role:master in response. [stage-15] Test passed. [stage-15] Terminating program @@ -1106,7 +1106,7 @@ Debug = true [stage-14] Program terminated successfully [stage-13] Running tests for Stage #13: rdb-read-value-with-expiry -[stage-13] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2288787261 --dbfilename banana.rdb +[stage-13] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3590791492 --dbfilename banana.rdb [stage-13] client: $ redis-cli GET blueberry [stage-13] client: Sent bytes: "*2\r\n$3\r\nGET\r\n$9\r\nblueberry\r\n" [stage-13] client: Received bytes: "$4\r\npear\r\n" @@ -1133,7 +1133,7 @@ Debug = true [stage-12] Running tests for Stage #12: rdb-read-multiple-string-values [stage-12] Created RDB file with key-value pairs: "pineapple"="pear", "raspberry"="strawberry", "pear"="pineapple" -[stage-12] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3373579943 --dbfilename strawberry.rdb +[stage-12] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles3660996270 --dbfilename strawberry.rdb [stage-12] client: $ redis-cli GET pineapple [stage-12] client: Sent bytes: "*2\r\n$3\r\nGET\r\n$9\r\npineapple\r\n" [stage-12] client: Received bytes: "$4\r\npear\r\n" @@ -1155,19 +1155,19 @@ Debug = true [stage-11] Running tests for Stage #11: rdb-read-multiple-keys [stage-11] Created RDB file with 4 keys: ["mango" "pineapple" "blueberry" "apple"] -[stage-11] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2105260986 --dbfilename strawberry.rdb +[stage-11] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1711702791 --dbfilename strawberry.rdb [stage-11] client: $ redis-cli KEYS * [stage-11] client: Sent bytes: "*2\r\n$4\r\nKEYS\r\n$1\r\n*\r\n" -[stage-11] client: Received bytes: "*4\r\n$9\r\nblueberry\r\n$9\r\npineapple\r\n$5\r\napple\r\n$5\r\nmango\r\n" -[stage-11] client: Received RESP value: ["blueberry", "pineapple", "apple", "mango"] -[stage-11] Received ["blueberry", "pineapple", "apple", "mango"] +[stage-11] client: Received bytes: "*4\r\n$5\r\napple\r\n$9\r\npineapple\r\n$5\r\nmango\r\n$9\r\nblueberry\r\n" +[stage-11] client: Received RESP value: ["apple", "pineapple", "mango", "blueberry"] +[stage-11] Received ["apple", "pineapple", "mango", "blueberry"] [stage-11] Test passed. [stage-11] Terminating program [stage-11] Program terminated successfully [stage-10] Running tests for Stage #10: rdb-read-string-value [stage-10] Created RDB file with single key-value pair: grape="raspberry" -[stage-10] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles675162260 --dbfilename blueberry.rdb +[stage-10] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles100393466 --dbfilename blueberry.rdb [stage-10] client: $ redis-cli GET grape [stage-10] client: Sent bytes: "*2\r\n$3\r\nGET\r\n$5\r\ngrape\r\n" [stage-10] client: Received bytes: "$9\r\nraspberry\r\n" @@ -1179,7 +1179,7 @@ Debug = true [stage-9] Running tests for Stage #9: rdb-read-key [stage-9] Created RDB file with single key: "orange" -[stage-9] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2196580614 --dbfilename mango.rdb +[stage-9] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles2772913819 --dbfilename mango.rdb [stage-9] client: $ redis-cli KEYS * [stage-9] client: Sent bytes: "*2\r\n$4\r\nKEYS\r\n$1\r\n*\r\n" [stage-9] client: Received bytes: "*1\r\n$6\r\norange\r\n" @@ -1190,12 +1190,12 @@ Debug = true [stage-9] Program terminated successfully [stage-8] Running tests for Stage #8: rdb-config -[stage-8] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1697795294 --dbfilename raspberry.rdb +[stage-8] $ ./spawn_redis_server.sh --dir /private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles419784592 --dbfilename raspberry.rdb [stage-8] client: $ redis-cli CONFIG GET dir [stage-8] client: Sent bytes: "*3\r\n$6\r\nCONFIG\r\n$3\r\nGET\r\n$3\r\ndir\r\n" -[stage-8] client: Received bytes: "*2\r\n$3\r\ndir\r\n$75\r\n/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1697795294\r\n" -[stage-8] client: Received RESP value: ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1697795294"] -[stage-8] Received ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles1697795294"] +[stage-8] client: Received bytes: "*2\r\n$3\r\ndir\r\n$74\r\n/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles419784592\r\n" +[stage-8] client: Received RESP value: ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles419784592"] +[stage-8] Received ["dir", "/private/var/folders/5l/z5y3dkwn68sgb6htzc5w7vnm0000gn/T/rdbfiles419784592"] [stage-8] Test passed. [stage-8] Terminating program [stage-8] Program terminated successfully @@ -1207,15 +1207,15 @@ Debug = true [stage-7] Received bytes: "+OK\r\n" [stage-7] Received RESP value: "OK" [stage-7] Received "OK" -[stage-7] Received OK at 14:50:31.545 -[stage-7] Fetching key "banana" at 14:50:31.545 (should not be expired) +[stage-7] Received OK at 14:57:19.418 +[stage-7] Fetching key "banana" at 14:57:19.418 (should not be expired) [stage-7] $ redis-cli GET banana [stage-7] Sent bytes: "*2\r\n$3\r\nGET\r\n$6\r\nbanana\r\n" [stage-7] Received bytes: "$6\r\norange\r\n" [stage-7] Received RESP value: "orange" [stage-7] Received "orange" [stage-7] Sleeping for 101ms -[stage-7] Fetching key "banana" at 14:50:31.649 (should be expired) +[stage-7] Fetching key "banana" at 14:57:19.520 (should be expired) [stage-7] $ redis-cli GET banana [stage-7] Sent bytes: "*2\r\n$3\r\nGET\r\n$6\r\nbanana\r\n" [stage-7] Received bytes: "$-1\r\n"