Skip to content

Instantly share code, notes, and snippets.

@vilterp

vilterp/simpletester-log.txt

Created February 25, 2015 09:40
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save vilterp/05c1c943cc8c0614c5c1 to your computer and use it in GitHub Desktop.
Save vilterp/05c1c943cc8c0614c5c1 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
simpletester-log.txt
[2015-02-23 22:22:57] INFO lt-chitcpd chitcpd running. UNIX socket: /tmp/chitcpd.socket.vilterp. TCP socket: 8745
[2015-02-23 22:22:57] DEBUG lt-chitcpd Waiting for chiTCP daemon to stop.
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=SOCKET)
[2015-02-23 22:22:59] TRACE handler-1 Received request (code=SOCKET)
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__SOCKET
[2015-02-23 22:22:59] TRACE handler-1 >>> Entering handler for CHITCPD_MSG_CODE__SOCKET
[2015-02-23 22:22:59] DEBUG handler-0 Assigned socket 0
[2015-02-23 22:22:59] DEBUG handler-1 Assigned socket 1
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__SOCKET
[2015-02-23 22:22:59] TRACE handler-1 <<< Exiting handler for CHITCPD_MSG_CODE__SOCKET
[2015-02-23 22:22:59] TRACE unix_server >>> Initializing debug connection
[2015-02-23 22:22:59] DEBUG unix_server Created new debug monitor for socket 0
[2015-02-23 22:22:59] TRACE unix_server <<< Finished initializing debug connection
[2015-02-23 22:22:59] TRACE unix_server >>> Initializing debug connection
[2015-02-23 22:22:59] DEBUG unix_server Created new debug monitor for socket 1
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=BIND)
[2015-02-23 22:22:59] TRACE unix_server <<< Finished initializing debug connection
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__BIND
[2015-02-23 22:22:59] DEBUG handler-0 Socket 0 will take port 7
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__BIND
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=LISTEN)
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__LISTEN
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__LISTEN
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=ACCEPT)
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__ACCEPT
[2015-02-23 22:22:59] TRACE handler-1 Received request (code=CONNECT)
[2015-02-23 22:22:59] TRACE handler-1 >>> Entering handler for CHITCPD_MSG_CODE__CONNECT
[2015-02-23 22:22:59] DEBUG handler-1 No connection entry found, creating one.
[2015-02-23 22:22:59] INFO network_server TCP connection received from 127.0.0.1:56665
[2015-02-23 22:22:59] TRACE handler-1 Signaling socket thread...
[2015-02-23 22:22:59] DEBUG tcp--fd-1 TCP thread running
[2015-02-23 22:22:59] TRACE handler-1 Waiting for ESTABLISHED...
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: app_connect
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event APPLICATION_CONNECT on state CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 0 RCV.NXT: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 0 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE tcp--fd-1 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-1 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Src: 49152 Dest: 7 Seq: 0 Ack: 0 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-1 > CWR: 0 ECE: 0 URG: 0 ACK: 0 PSH: 0 RST: 0 SYN: 1 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-1 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Reached a breakpoint: socket 1, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 49152 Dest: 7 Seq: 0 Ack: 0 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 0 PSH: 0 RST: 0 SYN: 1 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for passive socket 0 (port 7)
[2015-02-23 22:22:59] DEBUG connect-0 Enqueueing packet as pending connection in passive socket
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG handler-0 Assigned socket 2
[2015-02-23 22:22:59] DEBUG handler-0 >>> Reached a breakpoint: socket 0, event PENDING_CONNECTION
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] DEBUG handler-0 Added debug monitor for new active socket 2
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] DEBUG handler-0 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE handler-0 accept() initial packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE handler-0 Signaling socket thread...
[2015-02-23 22:22:59] DEBUG tcp--fd-2 TCP thread running
[2015-02-23 22:22:59] TRACE handler-0 Waiting for ESTABLISHED...
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SYN_SENT
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 1 RCV.NXT: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 0 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event APPLICATION_CONNECT on state CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< New state: SYN_SENT
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event PACKET_ARRIVAL on state LISTEN
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 LISTEN
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 0 RCV.NXT: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 0 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Point A
[2015-02-23 22:22:59] TRACE tcp--fd-2 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-2 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Src: 7 Dest: 49152 Seq: 0 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-2 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 1 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-2 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 7 Dest: 49152 Seq: 0 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 1 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 1 (port 49152)
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event PACKET_ARRIVAL on state SYN_SENT
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SYN_SENT
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 1 RCV.NXT: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 0 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-1 send_ack 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-1 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Src: 49152 Dest: 7 Seq: 1 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-1 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-1 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Reached a breakpoint: socket 1, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 49152 Dest: 7 Seq: 1 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Reached a breakpoint: socket 2, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 2 (port 7)
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-1 Socket connection is ESTABLISHED
[2015-02-23 22:22:59] TRACE handler-1 <<< Exiting handler for CHITCPD_MSG_CODE__CONNECT
[2015-02-23 22:22:59] DEBUG tcp--fd-1 About to free packet.
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 1 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-4 Received request (code=WAIT_FOR_STATE)
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event PACKET_ARRIVAL on state SYN_SENT
[2015-02-23 22:22:59] TRACE handler-4 >>> Entering handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] TRACE handler-4 Socket 1 is ESTABLISHED. Waiting for ESTABLISHED.
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< New state: ESTABLISHED
[2015-02-23 22:22:59] TRACE handler-4 <<< Exiting handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Exiting breakpoint
[2015-02-23 22:22:59] DEBUG tcp--fd-2 About to free packet.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SYN_RCVD
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 1 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event PACKET_ARRIVAL on state LISTEN
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< New state: SYN_RCVD
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event PACKET_ARRIVAL on state SYN_RCVD
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SYN_RCVD
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 1 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Reached a breakpoint: socket 2, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE handler-0 Socket connection is ESTABLISHED
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__ACCEPT
[2015-02-23 22:22:59] DEBUG tcp--fd-2 About to free packet.
[2015-02-23 22:22:59] TRACE handler-4 Received request (code=WAIT_FOR_STATE)
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 1 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] TRACE handler-4 >>> Entering handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event PACKET_ARRIVAL on state SYN_RCVD
[2015-02-23 22:22:59] TRACE handler-4 Socket 2 is ESTABLISHED. Waiting for ESTABLISHED.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< New state: ESTABLISHED
[2015-02-23 22:22:59] TRACE handler-4 <<< Exiting handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=RECV)
[2015-02-23 22:22:59] TRACE handler-1 Received request (code=SEND)
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__RECV
[2015-02-23 22:22:59] TRACE handler-1 >>> Entering handler for CHITCPD_MSG_CODE__SEND
[2015-02-23 22:22:59] TRACE handler-1 <<< Exiting handler for CHITCPD_MSG_CODE__SEND
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: app_send
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event APPLICATION_SEND on state ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 1 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 14 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE handler-1 Received request (code=CLOSE)
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send window: 536
[2015-02-23 22:22:59] TRACE handler-1 >>> Entering handler for CHITCPD_MSG_CODE__CLOSE
[2015-02-23 22:22:59] TRACE tcp--fd-1 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-1 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Payload length: 34
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE handler-1 >>> CLOSE sockfd=1
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Src: 49152 Dest: 7 Seq: 1 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-1 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Payload (14 bytes):
[2015-02-23 22:22:59] TRACE tcp--fd-1 0000 48 65 6c 6c 6f 2c 20 63 68 69 54 43 50 21 Hello, chiTCP!
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE handler-1 Signaling socket thread...
[2015-02-23 22:22:59] TRACE handler-1 Waiting for closing state...
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Sent a packet
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 15 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event APPLICATION_SEND on state ESTABLISHED
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: app_close
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event APPLICATION_CLOSE on state ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 15 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE tcp--fd-1 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-1 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Src: 49152 Dest: 7 Seq: 16 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-1 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Reached a breakpoint: socket 1, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 34
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 49152 Dest: 7 Seq: 1 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < Payload (14 bytes):
[2015-02-23 22:22:59] TRACE connect-0 0000 48 65 6c 6c 6f 2c 20 63 68 69 54 43 50 21 Hello, chiTCP!
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 2 (port 7)
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 49152 Dest: 7 Seq: 16 Ack: 1 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 1
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 2 (port 7)
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event PACKET_ARRIVAL on state ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 1 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 2 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Point A
[2015-02-23 22:22:59] DEBUG handler-0 recv() has extracted 14 bytes from the recv buffer
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__RECV
[2015-02-23 22:22:59] DEBUG tcp--fd-2 send_ack 4
[2015-02-23 22:22:59] TRACE tcp--fd-2 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-2 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Src: 7 Dest: 49152 Seq: 1 Ack: 15 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-2 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-2 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] DEBUG tcp--fd-2 About to free packet.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 1 RCV.NXT: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] TRACE handler-4 Received request (code=WAIT_FOR_STATE)
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event PACKET_ARRIVAL on state ESTABLISHED
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=CLOSE)
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 7 Dest: 49152 Seq: 1 Ack: 15 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__CLOSE
[2015-02-23 22:22:59] TRACE handler-0 >>> CLOSE sockfd=2
[2015-02-23 22:22:59] TRACE handler-0 Signaling socket thread...
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-4 >>> Entering handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-0 Waiting for closing state...
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: app_close
[2015-02-23 22:22:59] TRACE handler-4 Socket 1 is FIN_WAIT_1. Waiting for CLOSED.
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 1 (port 49152)
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event APPLICATION_CLOSE on state ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ESTABLISHED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 1 RCV.NXT: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event APPLICATION_CLOSE on state ESTABLISHED
[2015-02-23 22:22:59] TRACE tcp--fd-2 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< New state: FIN_WAIT_1
[2015-02-23 22:22:59] TRACE tcp--fd-2 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Src: 7 Dest: 49152 Seq: 2 Ack: 15 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-2 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 1
[2015-02-23 22:22:59] TRACE tcp--fd-2 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Reached a breakpoint: socket 2, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 7 Dest: 49152 Seq: 2 Ack: 15 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 1
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 1 (port 49152)
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event PACKET_ARRIVAL on state FIN_WAIT_1
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 2 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Point A
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-1 About to free packet.
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event PACKET_ARRIVAL on state FIN_WAIT_1
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event PACKET_ARRIVAL on state FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Exiting breakpoint
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Sending ack to fin
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event APPLICATION_CLOSE on state ESTABLISHED
[2015-02-23 22:22:59] TRACE tcp--fd-1 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< New state: FIN_WAIT_1
[2015-02-23 22:22:59] TRACE tcp--fd-1 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-1 > Src: 49152 Dest: 7 Seq: 16 Ack: 2 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-1 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-1 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-1 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: app_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Reached a breakpoint: socket 1, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event APPLICATION_RECEIVE on state FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] WARN tcp--fd-2 In FIN_WAIT_1 state, received unexpected event (3).
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event APPLICATION_RECEIVE on state FIN_WAIT_1
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event PACKET_ARRIVAL on state FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 FIN_WAIT_1
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Sending ack to fin
[2015-02-23 22:22:59] TRACE tcp--fd-2 Sending a chiTCP packet with a TCP payload.
[2015-02-23 22:22:59] TRACE tcp--fd-2 chiTCP Header:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Payload length: 20
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Protocol: 1
[2015-02-23 22:22:59] TRACE tcp--fd-2 ======================================================================
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP payload:
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] TRACE tcp--fd-2 > Src: 7 Dest: 49152 Seq: 2 Ack: 16 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE tcp--fd-2 > CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE tcp--fd-2 > No Payload
[2015-02-23 22:22:59] TRACE tcp--fd-2 ######################################################################
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Reached a breakpoint: socket 2, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 49152 Dest: 7 Seq: 16 Ack: 2 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 2 (port 7)
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Exiting breakpoint
[2015-02-23 22:22:59] DEBUG tcp--fd-1 About to free packet.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE handler-4 Socket 1 is CLOSING. Waiting for CLOSED.
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE handler-0 Socket connection is closing
[2015-02-23 22:22:59] DEBUG tcp--fd-2 About to free packet.
[2015-02-23 22:22:59] TRACE handler-1 Socket connection is closing
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event PACKET_ARRIVAL on state FIN_WAIT_1
[2015-02-23 22:22:59] TRACE handler-1 <<< Exiting handler for CHITCPD_MSG_CODE__CLOSE
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__CLOSE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< New state: CLOSING
[2015-02-23 22:22:59] TRACE handler-0 Received request (code=CLOSE)
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event PACKET_ARRIVAL on state FIN_WAIT_1
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] TRACE connect-0 Received a chiTCP header.
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 < Payload length: 20
[2015-02-23 22:22:59] TRACE connect-0 < Protocol: 1
[2015-02-23 22:22:59] TRACE connect-0 ======================================================================
[2015-02-23 22:22:59] TRACE connect-0 chiTCP packet contains a TCP payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] TRACE connect-0 < Src: 7 Dest: 49152 Seq: 2 Ack: 16 Doff: 5 Win: 536
[2015-02-23 22:22:59] TRACE connect-0 < CWR: 0 ECE: 0 URG: 0 ACK: 1 PSH: 0 RST: 0 SYN: 0 FIN: 0
[2015-02-23 22:22:59] TRACE connect-0 < No Payload
[2015-02-23 22:22:59] TRACE connect-0 ######################################################################
[2015-02-23 22:22:59] DEBUG connect-0 Received packet for active socket 1 (port 49152)
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet: enqueueing a copy
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point A
[2015-02-23 22:22:59] TRACE connect-0 chitcpd_enqueue_packet Point B
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< New state: CLOSING
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] TRACE tcp--fd-2 Event received: net_recv
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event PACKET_ARRIVAL on state CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 1
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] TRACE handler-0 >>> Entering handler for CHITCPD_MSG_CODE__CLOSE
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Point A
[2015-02-23 22:22:59] TRACE handler-0 >>> CLOSE sockfd=0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Reached a breakpoint: socket 2, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE handler-0 Freeing entry for passive socket 0
[2015-02-23 22:22:59] TRACE tcp--fd-1 Event received: net_recv
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event PACKET_ARRIVAL on state CLOSING
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 15
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 1 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Point A
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Reached a breakpoint: socket 1, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Exiting breakpoint
[2015-02-23 22:22:59] TRACE handler-0 Finished freeing entry for socket 0
[2015-02-23 22:22:59] TRACE handler-0 <<< Exiting handler for CHITCPD_MSG_CODE__CLOSE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Reached a breakpoint: socket 2, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-3 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-3 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-3 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Exiting breakpoint
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Reached a breakpoint: socket 1, event TCP_STATE_CHANGE
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Exiting breakpoint
[2015-02-23 22:22:59] DEBUG tcp--fd-2 About to free packet.
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event PACKET_ARRIVAL on state CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< New state: CLOSED
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-2 Waiting for TCP event
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Event received: cleanup
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> Handling event CLEANUP on state CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-2 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.UNA: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.NXT: 2 RCV.NXT: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-2 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-2
[2015-02-23 22:22:59] DEBUG tcp--fd-2 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-2 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-2 <<< Finished handling event CLEANUP on state CLOSED
[2015-02-23 22:22:59] TRACE tcp--fd-2 Freeing entry for active socket 2
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_STATE)
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_STATE
[2015-02-23 22:22:59] TRACE tcp--fd-2 Finished freeing entry for socket 2
[2015-02-23 22:22:59] TRACE tcp--fd-2 TCP event has been handled
[2015-02-23 22:22:59] TRACE handler-2 Received request (code=GET_SOCKET_BUFFER_CONTENTS)
[2015-02-23 22:22:59] TRACE handler-2 >>> Entering handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] TRACE handler-2 <<< Exiting handler for CHITCPD_MSG_CODE__GET_SOCKET_BUFFER_CONTENTS
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Exiting breakpoint
[2015-02-23 22:22:59] DEBUG tcp--fd-1 About to free packet.
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event PACKET_ARRIVAL on state CLOSING
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< New state: CLOSED
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE tcp--fd-1 Waiting for TCP event
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Event received: cleanup
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> Handling event CLEANUP on state CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1 >>> TCP data BEFORE handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< TCP data AFTER handling:
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 CLOSED
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ISS: 0 IRS: 0
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.UNA: 16
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.NXT: 16 RCV.NXT: 2
[2015-02-23 22:22:59] DEBUG tcp--fd-1 SND.WND: 536 RCV.WND: 536
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Send Buffer: 0 / 4096 Recv Buffer: 0 / 4096
[2015-02-23 22:22:59] DEBUG tcp--fd-1
[2015-02-23 22:22:59] DEBUG tcp--fd-1 Pending packets: 0 Closing? NO
[2015-02-23 22:22:59] DEBUG tcp--fd-1 ······················································
[2015-02-23 22:22:59] DEBUG tcp--fd-1 <<< Finished handling event CLEANUP on state CLOSED
[2015-02-23 22:22:59] TRACE tcp--fd-1 Freeing entry for active socket 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 Finished freeing entry for socket 1
[2015-02-23 22:22:59] TRACE tcp--fd-1 TCP event has been handled
[2015-02-23 22:22:59] TRACE handler-4 Socket 1 is CLOSED. Waiting for CLOSED.
[2015-02-23 22:22:59] TRACE handler-4 <<< Exiting handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] TRACE handler-4 Received request (code=WAIT_FOR_STATE)
[2015-02-23 22:22:59] TRACE handler-4 >>> Entering handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] ERROR handler-4 Not a valid chisocket descriptor: 2
[2015-02-23 22:22:59] TRACE handler-4 <<< Exiting handler for CHITCPD_MSG_CODE__WAIT_FOR_STATE
[2015-02-23 22:22:59] DEBUG handler-0 Daemon client has disconnected. Freeing open sockets for this handler...
[2015-02-23 22:22:59] DEBUG handler-3 Daemon client has disconnected. Freeing open sockets for this handler...
[2015-02-23 22:22:59] DEBUG handler-0 This handler had no sockets to free.
[2015-02-23 22:22:59] DEBUG handler-0 Handler is exiting.
[2015-02-23 22:22:59] DEBUG handler-4 Daemon client has disconnected. Freeing open sockets for this handler...
[2015-02-23 22:22:59] DEBUG handler-3 This handler had no sockets to free.
[2015-02-23 22:22:59] DEBUG handler-2 Daemon client has disconnected. Freeing open sockets for this handler...
[2015-02-23 22:22:59] DEBUG handler-1 Daemon client has disconnected. Freeing open sockets for this handler...
[2015-02-23 22:22:59] DEBUG handler-2 This handler had no sockets to free.
[2015-02-23 22:22:59] DEBUG handler-2 Handler is exiting.
[2015-02-23 22:22:59] DEBUG handler-1 This handler had no sockets to free.
[2015-02-23 22:22:59] DEBUG handler-3 Handler is exiting.
[2015-02-23 22:22:59] DEBUG handler-4 This handler had no sockets to free.
[2015-02-23 22:22:59] DEBUG handler-1 Handler is exiting.
[2015-02-23 22:22:59] DEBUG handler-4 Handler is exiting.
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment