Hornbeam - Case 3.4 : Fail - 1003 ms @ 2023-08-10T21:35:03.137Z

Case Description
Send small text message, then send again with RSV = 4, then send Ping. Octets are sent in octet-wise chops.

Case Expectation
Echo for first message is received, but then connection is failed immediately, since RSV must be 0, when no extension defining RSV meaning has been negotiated. The Pong is not received.

Case Outcome
Actual events differ from any expected.
Expected:
{'OK': [('message', u'Hello, world!', False)], 'NON-STRICT': []}
Observed:
[('message', u'Hello, world!', False), ('message', u'Hello, world!', False)]

Case Closing Behavior
The connection was failed by the wrong endpoint (FAILED)

## Opening Handshake

    GET /runCase?case=31&agent=Hornbeam HTTP/1.1
    Host: localhost:9001
    Upgrade: websocket
    Connection: upgrade
    Sec-WebSocket-Key: dp7WZ5wnC4rHMJA/tgLGIA==
    Sec-WebSocket-Version: 13

    HTTP/1.1 101 Switching Protocols
    Server: AutobahnTestSuite/0.8.2-0.10.9
    X-Powered-By: AutobahnPython/0.10.9
    Upgrade: WebSocket
    Connection: Upgrade
    Sec-WebSocket-Accept: aAmnsEzsNUfy3nF7aRQkEA8xrMU=

## Closing Behavior

Key | Value | Description
isServer | True | True, iff I (the fuzzer) am a server, and the peer is a client.
closedByMe | True | True, iff I have initiated closing handshake (that is, did send close first).
failedByMe | True | True, 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.
droppedByMe | True | True, iff I dropped the TCP connection.
wasClean | True | True, iff full WebSocket closing handshake was performed (close frame sent and received) _and_ the server dropped the TCP (which is its responsibility).
wasNotCleanReason | None | When wasClean == False, the reason what happened.
wasServerConnectionDropTimeout | False | When we are a client, and we expected the server to drop the TCP, but that didn't happen in time, this gets True.
wasOpenHandshakeTimeout | False | When performing the opening handshake, but the peer did not finish in time, this gets True.
wasCloseHandshakeTimeout | False | When we initiated a closing handshake, but the peer did not respond in time, this gets True.
localCloseCode | 1001 | The close code I sent in close frame (if any).
localCloseReason | Going Away | The close reason I sent in close frame (if any).
remoteCloseCode | 1001 | The close code the peer sent me in close frame (if any).
remoteCloseReason | Going Away | The close reason the peer sent me in close frame (if any).

## Wire Statistics

### Octets Received by Chop Size

Chop Size | Count | Octets
18 | 1 | 18
19 | 2 | 38
183 | 1 | 183
Total | 4 | 239

### Octets Transmitted by Chop Size

Chop Size | Count | Octets
1 | 32 | 32
14 | 1 | 14
206 | 1 | 206
Total | 34 | 252

### Frames Received by Opcode

Opcode | Count
1 | 2
8 | 1
Total | 3

### Frames Transmitted by Opcode

Opcode | Count
1 | 2
8 | 1
9 | 1
Total | 4

## Wire Log

    000 RX OCTETS: 474554202f72756e436173653f636173653d3331266167656e743d486f726e6265616d20485454502f312e310d0a486f7374

                   3a206c6f63616c686f73743a3930 ...

    001 TX OCTETS: 485454502f312e312031303120537769746368696e672050726f746f636f6c730d0a5365727665723a204175746f6261686e

                   5465737453756974652f302e382e ...

    002 TX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=13, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=1, SYNC=False

                   Hello, world!

    003 TX OCTETS: 81

    004 TX FRAME : OPCODE=1, FIN=True, RSV=4, PAYLOAD-LEN=13, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=1, SYNC=False

                   Hello, world!

    005 TX FRAME : OPCODE=9, FIN=True, RSV=0, PAYLOAD-LEN=0, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=1, SYNC=False

    006 FAIL CONNECTION AFTER 1.000000 sec

    007 TX OCTETS: 0d

    008 TX OCTETS: 48

    009 TX OCTETS: 65

    010 TX OCTETS: 6c

    011 TX OCTETS: 6c

    012 TX OCTETS: 6f

    013 TX OCTETS: 2c

    014 TX OCTETS: 20

    015 TX OCTETS: 77

    016 TX OCTETS: 6f

    017 TX OCTETS: 72

    018 TX OCTETS: 6c

    019 TX OCTETS: 64

    020 TX OCTETS: 21

    021 TX OCTETS: c1

    022 TX OCTETS: 0d

    023 TX OCTETS: 48

    024 TX OCTETS: 65

    025 RX OCTETS: 818dc37157f18b143b9dac5d7786ac033b95e2

    026 RX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=13, MASKED=True, MASK=6333373135376631

                   Hello, world!

    027 TX OCTETS: 6c

    028 TX OCTETS: 6c

    029 TX OCTETS: 6f

    030 TX OCTETS: 2c

    031 TX OCTETS: 20

    032 TX OCTETS: 77

    033 TX OCTETS: 6f

    034 TX OCTETS: 72

    035 TX OCTETS: 6c

    036 TX OCTETS: 64

    037 TX OCTETS: 21

    038 TX OCTETS: 89

    039 TX OCTETS: 00

    040 RX OCTETS: 818d6b2ff793234a9bff0403d7e4045d9bf74a

    041 RX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=13, MASKED=True, MASK=3662326666373933

                   Hello, world!

    042 FAILING CONNECTION

    043 TX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=12, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False

                   0x03e9476f696e672041776179

    044 TX OCTETS: 880c03e9476f696e672041776179

    045 RX OCTETS: 888c8188e4608261a30fe8e68340c0ff8519

    046 RX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=12, MASKED=True, MASK=3831383865343630

                   0x03e9476f696e672041776179

    047 TCP DROPPED BY ME