Autobahn WebSocket Testsuite Report
Autobahn WebSocket

AVR / W5500 / Ethernet - Case 5.19 : Pass - 1073 ms @ 2020-06-03T10:54:41.635Z

Case Description

A fragmented text message is sent in multiple frames. After sending the first 2 frames of the text message, a Ping is sent. Then we wait 1s, then we send 2 more text fragments, another Ping and then the final text fragment. Everything is legal.

Case Expectation

The peer immediately answers the first Ping before it has received the last text message fragment. The peer pong's back the Ping's payload exactly, and echo's the payload of the fragmented message back to us.

Case Outcome

Actual events match at least one expected.

Expected:
{'OK': [('pong', u'pongme 1!'), ('pong', u'pongme 2!'), ('message', u'fragment1fragment2fragment3fragment4fragment5', False)]}

Observed:
[('pong', u'pongme 1!'), ('pong', u'pongme 2!'), ('message', u'fragment1fragment2fragment3fragment4fragment5', False)]

Case Closing Behavior

Connection was properly closed (OK)



Opening Handshake

GET /runCase?case=63&agent=AVR%20/%20W5500%20/%20Ethernet HTTP/1.1
Host: 192.168.46.31:9001
Upgrade: websocket
Connection: Upgrade
Sec-WebSocket-Key: 8kAon0FlENgzgRZi51duuQ==
Sec-WebSocket-Version: 13
HTTP/1.1 101 Switching Protocols
Server: AutobahnTestSuite/0.8.0-0.10.9
X-Powered-By: AutobahnPython/0.10.9
Upgrade: WebSocket
Connection: Upgrade
Sec-WebSocket-Accept: 7C+2+CF/cY1uVzy0X5HhkBW6J3s=


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
14141
2714
3515
4728
5210
616
818
11111
14114
16116
24124
43143
68168
Total70298

Octets Transmitted by Chop Size

Chop SizeCountOctets
414
11777
2061206
Total9287

Frames Received by Opcode

OpcodeCount
11
81
102
Total4

Frames Transmitted by Opcode

OpcodeCount
04
11
81
92
Total8


Wire Log

000 RX OCTETS: 474554202f72756e436173653f636173653d3633266167656e743d4156522532302f25323057353530302532302f25323045
               746865726e657420485454502f31 ...
001 RX OCTETS: 486f73743a203139322e3136382e34362e33313a39303031
002 RX OCTETS: 0d0a
003 RX OCTETS: 55706772
004 RX OCTETS: 6164653a20776562736f636b65740d0a
005 RX OCTETS: 436f6e6e
006 RX OCTETS: 65
007 RX OCTETS: 63
008 RX OCTETS: 74
009 RX OCTETS: 69
010 RX OCTETS: 6f
011 RX OCTETS: 6e
012 RX OCTETS: 3a
013 RX OCTETS: 20
014 RX OCTETS: 557067
015 RX OCTETS: 72
016 RX OCTETS: 61
017 RX OCTETS: 64
018 RX OCTETS: 65
019 RX OCTETS: 0d0a
020 RX OCTETS: 5365632d576562536f636b65742d4b65793a20386b416f6e30466c454e677a67525a693531647575513d3d
021 RX OCTETS: 0d0a
022 RX OCTETS: 53
023 RX OCTETS: 65
024 RX OCTETS: 632d
025 RX OCTETS: 57
026 RX OCTETS: 656253
027 RX OCTETS: 6f
028 RX OCTETS: 63
029 RX OCTETS: 6b
030 RX OCTETS: 6574
031 RX OCTETS: 2d
032 RX OCTETS: 56
033 RX OCTETS: 65
034 RX OCTETS: 72
035 RX OCTETS: 73696f
036 RX OCTETS: 6e
037 RX OCTETS: 3a203133
038 RX OCTETS: 0d
039 RX OCTETS: 0a0d0a
040 TX OCTETS: 485454502f312e312031303120537769746368696e672050726f746f636f6c730d0a5365727665723a204175746f6261686e
               5465737453756974652f302e382e ...
041 TX FRAME : OPCODE=1, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               fragment1
042 TX OCTETS: 0109667261676d656e7431
043 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               fragment2
044 TX OCTETS: 0009667261676d656e7432
045 TX FRAME : OPCODE=9, FIN=True, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               pongme 1!
046 TX OCTETS: 8909706f6e676d65203121
047 DELAY 1.000000 sec for TAG None
048 RX OCTETS: 8a89
049 RX OCTETS: 0a2d89797a
050 RX OCTETS: 42e7
051 RX OCTETS: 1e
052 RX OCTETS: 67
053 RX OCTETS: 48
054 RX OCTETS: a9
055 RX OCTETS: 48
056 RX OCTETS: 2b
057 RX FRAME : OPCODE=10, FIN=True, RSV=0, PAYLOAD-LEN=9, MASKED=True, MASK=3061326438393739
               pongme 1!
058 DELAY TIMEOUT on TAG None
059 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               fragment3
060 TX OCTETS: 0009667261676d656e7433
061 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               fragment4
062 TX OCTETS: 0009667261676d656e7434
063 TX FRAME : OPCODE=9, FIN=True, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               pongme 2!
064 TX OCTETS: 8909706f6e676d65203221
065 TX FRAME : OPCODE=0, FIN=True, RSV=0, PAYLOAD-LEN=9, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               fragment5
066 TX OCTETS: 8009667261676d656e7435
067 CLOSE CONNECTION AFTER 1.000000 sec
068 RX OCTETS: 8a
069 RX OCTETS: 890c005e9f
070 RX OCTETS: 7c
071 RX OCTETS: 6f30f8
072 RX OCTETS: 61
073 RX OCTETS: 657ead2d
074 RX FRAME : OPCODE=10, FIN=True, RSV=0, PAYLOAD-LEN=9, MASKED=True, MASK=3063303035653966
               pongme 2!
075 RX OCTETS: 81
076 RX OCTETS: ad
077 RX OCTETS: f8382712
078 RX OCTETS: 9e4a4675
079 RX OCTETS: 95
080 RX OCTETS: 5d4966c95e55
081 RX OCTETS: 73
082 RX OCTETS: 9f55427c8c0a4160995f4a77964c
083 RX OCTETS: 14748a59407f9d56
084 RX OCTETS: 53269e4a4675955d4966cd
085 RX FRAME : OPCODE=1, FIN=True, RSV=0, PAYLOAD-LEN=45, MASKED=True, MASK=6638333832373132
               fragment1fragment2fragment3fragment4fragment5
086 TX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASK=None, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
               0x03e8
087 TX OCTETS: 880203e8
088 RX OCTETS: 88
089 RX OCTETS: 82
090 RX OCTETS: 53195652
091 RX OCTETS: 50
092 RX OCTETS: f1
093 RX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASKED=True, MASK=3533313935363532
               0x03e8
094 TCP DROPPED BY ME