sgcWebSockets - Case 7.1.6 : Informational - 74 ms @ 2019-01-06T15:32:33.816Z

Case Description

Send 256K message followed by close then a ping

Case Expectation

Case outcome depends on implementation defined close behavior. Message and close frame are sent back to back. If the close frame is processed before the text message write is complete (as can happen in asynchronous processing models) the close frame is processed first and the text message may not be received or may only be partially recieved.

Case Outcome

Actual events differ from any expected.

Expected:
{'NON-STRICT': [], 'OK': [('message', u'Hello World!', False)]}

Observed:
[('message', u'BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd ...', False), ('message', u'Hello World!', False)]

Case Closing Behavior

Connection was properly closed (INFORMATIONAL)



Opening Handshake

GET /runCase?case=215&agent=sgcWebSockets HTTP/1.1

Host: 127.0.0.1:9001

Upgrade: websocket

Connection: Upgrade

Sec-WebSocket-Key: zqVES05WWwB3yICzE7cZ/g==

Origin: 127.0.0.1

Sec-WebSocket-Extensions: permessage-deflate; client_max_window_bits

Sec-WebSocket-Version: 13
HTTP/1.1 101 Switching Protocols

Server: AutobahnTestSuite/0.8.1-0.10.9

X-Powered-By: AutobahnPython/0.10.9

Upgrade: WebSocket

Connection: Upgrade

Sec-WebSocket-Accept: GWXmPHEcUUhEz59/wXjrien25o8=


Closing Behavior

KeyValueDescription
isServerTrueTrue, 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).
failedByMeFalseTrue, 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.
droppedByMeTrueTrue, 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.
localCloseCode1000The close code I sent in close frame (if any).
localCloseReasonNoneThe 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
2781278
13888113888
51688151688
655363196608
Total6262462

Octets Transmitted by Chop Size

Chop SizeCountOctets
212
414
14114
2061206
2621541262154
Total5262380

Frames Received by Opcode

OpcodeCount
12
81
Total3

Frames Transmitted by Opcode

OpcodeCount
12
81
91
Total4


Wire Log

000 RX OCTETS: 474554202f72756e436173653f636173653d323135266167656e743d736763576562536f636b65747320485454502f312e31
               0d0a486f73743a203132372e302e ...
001 TX OCTETS: 485454502f312e312031303120537769746368696e672050726f746f636f6c730d0a5365727665723a204175746f6261686e
               5465737453756974652f302e382e ...
002 TX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=10, MASK=None, PAYLOAD-REPEAT-LEN=262144, CHOPSIZE=None, SYNC=False
               BAsd7&jh23
003 TX OCTETS: 817f00000000000400004241736437266a6832334241736437266a6832334241736437266a6832334241736437266a683233
               4241736437266a68323342417364 ...
004 TX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=12, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               Hello World!
005 TX OCTETS: 810c48656c6c6f20576f726c6421
006 TX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               0x03e8
007 TX OCTETS: 880203e8
008 TX FRAME : OPCODE=9, FIN=True, RSV=0, PAYLOAD-LEN=0, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
009 TX OCTETS: 8900
010 FAIL CONNECTION AFTER 1.000000 sec
011 RX OCTETS: 81ff0000000000040000e0d32fdba2925cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd
               8abb1de8a2925cbfd7f545b3d2e0 ...
012 RX OCTETS: 18fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbf
               d7f545b3d2e06d9a93b718fd8abb ...
013 RX OCTETS: 6d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd8abb1de8
               a2925cbfd7f545b3d2e06d9a93b7 ...
014 RX OCTETS: 45b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd
               8abb1de8a2925cbfd7f545b3d2e0 ...
015 RX OCTETS: 5cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a93b718fd8abb1de8a2925cbfd7f545b3d2e06d9a
               93b718fd8abb1de8a2925cbfd7f5 ...
016 RX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=262144, MASKED=True, MASK=6530643332666462
               BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd7&jh23BAsd ...
017 RX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=12, MASKED=True, MASK=6431356134393434
               Hello World!
018 RX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASKED=True, MASK=6233323263373763
               0x03e8
019 TCP DROPPED BY ME