Autobahn WebSocket Testsuite Report
Autobahn WebSocket

go/echoserver - Case 6.4.1 : Fail - 1001 ms @ 2017-05-01T23:55:02.036Z

Case Description

Send invalid UTF-8 text message in 3 fragments (frames). First frame payload is valid, then wait, then 2nd frame which contains the payload making the sequence invalid, then wait, then 3rd frame with rest. Note that PART1 and PART3 are valid UTF-8 in themselves, PART2 is a 0x110000 encoded as in the UTF-8 integer encoding scheme, but the codepoint is invalid (out of range).

MESSAGE PARTS:
PART1 = cebae1bdb9cf83cebcceb5
PART2 = f4908080
PART3 = 656469746564

Case Expectation

The first frame is accepted, we expect to timeout on the first wait. The 2nd frame should be rejected immediately (fail fast on UTF-8). If we timeout, we expect the connection is failed at least then, since the complete message payload is not valid UTF-8.

Case Outcome

Actual events match at least one expected.

Expected:
{'NON-STRICT': [('timeout', 'A'), ('timeout', 'B')], 'OK': [('timeout', 'A')]}

Observed:
[('timeout', 'A')]

Case Closing Behavior

The connection was failed by the wrong endpoint (FAILED)



Opening Handshake

GET / HTTP/1.1
User-Agent: AutobahnTestSuite/0.7.6-0.10.9
Host: 127.0.0.1:9001
Upgrade: WebSocket
Connection: Upgrade
Pragma: no-cache
Cache-Control: no-cache
Sec-WebSocket-Key: kv0CeNQysMUqFA5AEiBhlw==
Sec-WebSocket-Version: 13
HTTP/1.1 101 Web Socket Protocol Handshake
Server: go/echoserver
Upgrade: WebSocket
Connection: Upgrade
Sec-WebSocket-Accept: zM1GpfWabs2uhJ2oRFkGgIOvRSs=


Closing Behavior

KeyValueDescription
isServerFalseTrue, iff I (the fuzzer) am a server, and the peer is a client.
closedByMeTrueTrue, iff I have initiated closing handshake (that is, did send close first).
failedByMeTrueTrue, iff I have failed the WS connection (i.e. due to protocol error). Failing can be either by initiating closing handshake or brutal drop TCP.
droppedByMeFalseTrue, iff I dropped the TCP connection.
wasCleanTrueTrue, iff full WebSocket closing handshake was performed (close frame sent and received) _and_ the server dropped the TCP (which is its responsibility).
wasNotCleanReasonNoneWhen wasClean == False, the reason what happened.
wasServerConnectionDropTimeoutFalseWhen we are a client, and we expected the server to drop the TCP, but that didn't happen in time, this gets True.
wasOpenHandshakeTimeoutFalseWhen performing the opening handshake, but the peer did not finish in time, this gets True.
wasCloseHandshakeTimeoutFalseWhen we initiated a closing handshake, but the peer did not respond in time, this gets True.
localCloseCode1007The close code I sent in close frame (if any).
localCloseReasonencountered invalid UTF-8 while processing text message at payload octet index 12The close reason I sent in close frame (if any).
remoteCloseCode1000The close code the peer sent me in close frame (if any).
remoteCloseReasonNoneThe close reason the peer sent me in close frame (if any).


Wire Statistics

Octets Received by Chop Size

Chop SizeCountOctets
414
616
13113
1621162
Total4185

Octets Transmitted by Chop Size

Chop SizeCountOctets
10110
17117
89189
2401240
Total4356

Frames Received by Opcode

OpcodeCount
01
11
81
Total3

Frames Transmitted by Opcode

OpcodeCount
01
11
81
Total3


Wire Log

000 TX OCTETS: 474554202f20485454502f312e310d0a557365722d4167656e743a204175746f6261686e5465737453756974652f302e372e
               362d302e31302e390d0a486f7374 ...
001 RX OCTETS: 485454502f312e31203130312057656220536f636b65742050726f746f636f6c2048616e647368616b650d0a536572766572
               3a20676f2f6563686f7365727665 ...
002 TX FRAME : OPCODE=1, FIN=False, RSV=0, PAYLOAD-LEN=11, MASK=4e29f56d, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               κόσμε
003 TX OCTETS: 018b4e29f56d809314d0f7e676a3f2e740
004 DELAY 1.000000 sec for TAG A
005 RX OCTETS: 010bcebae1bdb9cf83cebcceb5
006 RX FRAME : OPCODE=1, FIN=False, RSV=0, PAYLOAD-LEN=11, MASKED=False, MASK=None
               κόσμε
007 DELAY TIMEOUT on TAG A
008 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=4, MASK=2fcefe1b, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               0xf4908080
009 TX OCTETS: 00842fcefe1bdb5e7e9b
010 DELAY 1.000000 sec for TAG B
011 RX OCTETS: 0004f4908080
012 TX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=83, MASK=4a7ca146, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               0x03ef656e636f756e746572656420696e76616c6964205554462d38207768696c652070726f63657373696e672074657874
               206d65737361676520617420706179 ...
013 TX OCTETS: 88d34a7ca1464993c4282913d4283e19d3232e5cc8283c1dcd2f2e5cf4120c5199663d14c82a2f5cd134251fc4353915cf21
               6a08c43e3e5ccc23390fc0212f5c ...
014 RX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=0, MASKED=False, MASK=None
015 RX OCTETS: 880203e8
016 RX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASKED=False, MASK=None
               0x03e8
017 TCP DROPPED BY PEER