go/echoserver - Case 5.19 : Pass - 1001 ms @ 2017-05-01T23:55:00.013Z
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)
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: TTKhpKFz00JXfnnzXCA1Qg== Sec-WebSocket-Version: 13
HTTP/1.1 101 Web Socket Protocol Handshake Server: go/echoserver Upgrade: WebSocket Connection: Upgrade Sec-WebSocket-Accept: kDf3+fjcp+M0N+gJqDQF26q4X2U=
| Key | Value | Description |
| isServer | False | 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 | False | 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 | False | 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 | 1000 | The close code I sent in close frame (if any). |
| localCloseReason | None | The close reason I sent in close frame (if any). |
| remoteCloseCode | 1000 | The close code the peer sent me in close frame (if any). |
| remoteCloseReason | None | The close reason the peer sent me in close frame (if any). |
| Chop Size | Count | Octets |
| 4 | 1 | 4 |
| 11 | 1 | 11 |
| 22 | 3 | 66 |
| 162 | 1 | 162 |
| Total | 6 | 243 |
| Chop Size | Count | Octets |
| 8 | 1 | 8 |
| 15 | 7 | 105 |
| 240 | 1 | 240 |
| Total | 9 | 353 |
| Opcode | Count |
| 0 | 4 |
| 1 | 1 |
| 8 | 1 |
| 10 | 2 |
| Total | 8 |
| Opcode | Count |
| 0 | 4 |
| 1 | 1 |
| 8 | 1 |
| 9 | 2 |
| Total | 8 |
000 TX OCTETS: 474554202f20485454502f312e310d0a557365722d4167656e743a204175746f6261686e5465737453756974652f302e372e
362d302e31302e390d0a486f7374 ...
001 RX OCTETS: 485454502f312e31203130312057656220536f636b65742050726f746f636f6c2048616e647368616b650d0a536572766572
3a20676f2f6563686f7365727665 ...
002 TX FRAME : OPCODE=1, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=ef65a637, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
fragment1
003 TX OCTETS: 0189ef65a6378917c7508200c843de
004 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=7163b9dd, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
fragment2
005 TX OCTETS: 00897163b9dd1711d8ba1c06d7a943
006 TX FRAME : OPCODE=9, FIN=True, RSV=0, PAYLOAD-LEN=9, MASK=46df5992, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
pongme 1!
007 TX OCTETS: 898946df599236b037f52bba79a367
008 DELAY 1.000000 sec for TAG None
009 RX OCTETS: 0109667261676d656e74310009667261676d656e7432
010 RX FRAME : OPCODE=1, FIN=False, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
fragment1
011 RX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
fragment2
012 RX OCTETS: 8a09706f6e676d65203121
013 RX FRAME : OPCODE=10, FIN=True, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
pongme 1!
014 DELAY TIMEOUT on TAG None
015 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=4b209a71, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
fragment3
016 TX OCTETS: 00894b209a712d52fb162645f40578
017 TX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASK=d5bb41cb, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
fragment4
018 TX OCTETS: 0089d5bb41cbb3c920acb8de2fbfe1
019 TX FRAME : OPCODE=9, FIN=True, RSV=0, PAYLOAD-LEN=9, MASK=2e2e2602, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
pongme 2!
020 TX OCTETS: 89892e2e26025e414865434b06300f
021 TX FRAME : OPCODE=0, FIN=True, RSV=0, PAYLOAD-LEN=9, MASK=3d3e236c, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
fragment5
022 TX OCTETS: 80893d3e236c5b4c420b505b4d1808
023 CLOSE CONNECTION AFTER 1.000000 sec
024 RX OCTETS: 0009667261676d656e74330009667261676d656e7434
025 RX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
fragment3
026 RX FRAME : OPCODE=0, FIN=False, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
fragment4
027 RX OCTETS: 8a09706f6e676d652032218009667261676d656e7435
028 RX FRAME : OPCODE=10, FIN=True, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
pongme 2!
029 RX FRAME : OPCODE=0, FIN=True, RSV=0, PAYLOAD-LEN=9, MASKED=False, MASK=None
fragment5
030 TX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASK=463a845e, PAYLOAD-REPEAT-LEN=None, CHOPSIZE=None, SYNC=False
0x03e8
031 TX OCTETS: 8882463a845e45d2
032 RX OCTETS: 880203e8
033 RX FRAME : OPCODE=8, FIN=True, RSV=0, PAYLOAD-LEN=2, MASKED=False, MASK=None
0x03e8
034 TCP DROPPED BY PEER