[
https://issues.apache.org/jira/browse/DISPATCH-1878?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17358831#comment-17358831
]
Charles E. Rolke commented on DISPATCH-1878:
--------------------------------------------
>From a 4-router linear test failure.
The original message 'abcd' makes it all the way to router D and to the server.
See 15:02:28.163031
Then the client connection closure arrives at D 15:02:28.163008 before the
server's reply 15:02:28.164710
A receives the modified disposition at 15:02:28.164710 and proceeds to close
TCP connection to the ingress client.
The final data packet destined for the ingress client arrives at A at
15:02:28.167786 where it gets released.
{code:java}
◊ 2021-06-07 15:02:28.154261 A0#1827 A [] [] TCP_ADAPTOR (info)
PN_LISTENER_ACCEPT Accepting TCP connection to 0.0.0.0:9999
◊ 2021-06-07 15:02:28.154459 A0#1828 A [] [] TCP_ADAPTOR (info) [C0] call
pn_listener_raw_accept()
◊ 2021-06-07 15:02:28.155151 A0#1832 A [] [] TCP_ADAPTOR (info) [C2]
PN_RAW_CONNECTION_CONNECTED Listener ingress accepted to 0.0.0.0:9999 from
127.0.0.1:44294 (global_id=127.0.0.1:44294@mySite)
◊ 2021-06-07 15:02:28.155238 A0#1833 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_NEED_WRITE_BUFFERS listener
◊ 2021-06-07 15:02:28.155314 A0#1835 A [] [] TCP_ADAPTOR (debug) [C2]
qdr_tcp_activate: call pn_raw_connection_wake()
◊ 2021-06-07 15:02:28.155334 A0#1836 A [] [] TCP_ADAPTOR (debug) [C2][L23]
(listener outgoing) qdr_tcp_second_attach
◊ 2021-06-07 15:02:28.155425 A0#1838 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming qdr_tcp_second_attach for listener connection. read_closed:F,
flow_enabled:F
◊ 2021-06-07 15:02:28.155452 A0#1839 A [] [] TCP_ADAPTOR (debug) [C2][L24]
Waiting for credit before initiating listener ingress stream message
◊ 2021-06-07 15:02:28.155510 A0#1841 A [] [] TCP_ADAPTOR (debug) [C2][L23]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.155688 A0#1845 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_WAKE listener
◊ 2021-06-07 15:02:28.155790 A0#1847 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_WAKE for listener connection. read_closed:F, flow_enabled:F
◊ 2021-06-07 15:02:28.155857 A0#1849 A [] [] TCP_ADAPTOR (debug) [C2][L24]
Waiting for credit before initiating listener ingress stream message
◊ 2021-06-07 15:02:28.155884 A0#1850 A [] [] TCP_ADAPTOR (debug) [C2]
qdr_add_tcp_connection_CT 0.0.0.0:9999 (1)
◊ 2021-06-07 15:02:28.155918 A0#1851 A [] [] TCP_ADAPTOR (debug) [C2][L23]
qdr_tcp_flow: Flow enabled, credit=250
◊ 2021-06-07 15:02:28.155941 A0#1852 A [] [] TCP_ADAPTOR (debug) [C2]
qdr_tcp_activate: call pn_raw_connection_wake()
◊ 2021-06-07 15:02:28.155972 A0#1853 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming qdr_tcp_flow for listener connection. read_closed:F,
flow_enabled:T
◊ 2021-06-07 15:02:28.156031 A0#1855 A [] [] TCP_ADAPTOR (debug) [C2][L24]
Initiating listener (ingress) stream incoming link for listener connection to:
nc reply: amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ
◊ 2021-06-07 15:02:28.156166 A0#1859 A [] [] TCP_ADAPTOR (debug)
[C2][L24][D88] Initiating empty listener incoming stream message
◊ 2021-06-07 15:02:28.156223 A0#1861 A [] [] TCP_ADAPTOR (debug) [C2][L24]
Calling pn_raw_connection_give_read_buffers() capacity=32768 offset=0
◊ 2021-06-07 15:02:28.156249 A0#1862 A [] [] TCP_ADAPTOR (debug) [C2][L24]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.156273 A0#1863 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_WAKE listener
◊ 2021-06-07 15:02:28.156289 A0#1864 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_WAKE for listener connection. read_closed:F, flow_enabled:T
◊ 2021-06-07 15:02:28.156352 A0#1865 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_READ listener Event
◊ 2021-06-07 15:02:28.156372 A0#1866 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_READ for listener connection. read_closed:F, flow_enabled:T
◊ 2021-06-07 15:02:28.156389 A0#1867 A [] [] TCP_ADAPTOR (debug) [C2]
pn_raw_connection_take_read_buffers() took buffer with 5 bytes
◊ 2021-06-07 15:02:28.156411 A0#1868 A [] [] TCP_ADAPTOR (debug) [C2][L24]
Calling pn_raw_connection_give_read_buffers() capacity=32768 offset=0
◊ 2021-06-07 15:02:28.156483 A0#1870 A [] [] TCP_ADAPTOR (trace)
[C2][L24][D88] Continuing listener message with 5 bytes
◊ 2021-06-07 15:02:28.156504 A0#1871 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_READ Read 5 bytes. Total read 5 bytes
◊ 2021-06-07 15:02:28.156571 A0#1873 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_READ listener Event
◊ 2021-06-07 15:02:28.156596 A0#1874 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_READ for listener connection. read_closed:F, flow_enabled:T
◊ 2021-06-07 15:02:28.156612 A0#1875 A [] [] TCP_ADAPTOR (debug) [C2]
pn_raw_connection_take_read_buffers() took buffer with 0 bytes
◊ 2021-06-07 15:02:28.156631 A0#1876 A [] [] TCP_ADAPTOR (debug) [C2][L24]
Calling pn_raw_connection_give_read_buffers() capacity=32768 offset=0
◊ 2021-06-07 15:02:28.156652 A0#1877 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_READ Read 0 bytes. Total read 5 bytes
◊ 2021-06-07 15:02:28.156699 A0#1879 A [] [] TCP_ADAPTOR (debug) [C2][L24]
PN_RAW_CONNECTION_CLOSED_READ listener
◊ 2021-06-07 15:02:28.156760 A0#1881 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_CLOSED_READ for listener connection. read_closed:T,
flow_enabled:T
◊ 2021-06-07 15:02:28.156809 A0#1883 A [] [] TCP_ADAPTOR (debug)
[C2][L24][D88] close listener instream delivery
◊ 2021-06-07 15:02:28.157217 A0#1892 A [A0_1] -> [B0_1] B begin [11,None)]
◊ 2021-06-07 15:02:28.157291 A0#1893 A [A0_1] -> [B0_1] B attach [11,0] sender
link_66 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.157440 B0#3135 B [B0_1] <- [A0_1] A begin [11,None)]
◊ 2021-06-07 15:02:28.157557 B0#3136 B [B0_1] <- [A0_1] A attach [11,0] sender
link_66 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.157925 B0#3138 B [B0_1] -> [A0_1] A begin [11,11]
◊ 2021-06-07 15:02:28.158047 A0#1894 A [A0_1] <- [B0_1] B begin [11,11]
◊ 2021-06-07 15:02:28.158121 B0#3140 B [B0_1] -> [A0_1] A attach [11,0]
receiver link_66 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.158172 B0#3141 B [B0_1] -> [A0_1] A flow [11,0] (0,250)
◊ 2021-06-07 15:02:28.158329 A0#1895 A [A0_1] <- [B0_1] B attach [11,0]
receiver link_66 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.158382 A0#1896 A [A0_1] <- [B0_1] B flow [11,0] (0,250)
◊ 2021-06-07 15:02:28.158684 A0#1903 A [A0_1] -> [B0_1] B transfer [11,0] (0)
transfer_48 te $amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ Su abcd " - 177 bytes
(modified settled 0.008098 S)
◊ 2021-06-07 15:02:28.158795 B0#3142 B [B0_1] <- [A0_1] A transfer [11,0] (0)
transfer_48 te $amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ Su abcd " - 177 bytes
(modified settled 0.007815 S)
◊ 2021-06-07 15:02:28.159369 B0#3156 B [B0_1] -> [A0_1] A flow [11,0] (1,250)
◊ 2021-06-07 15:02:28.159508 A0#1905 A [A0_1] <- [B0_1] B flow [11,0] (1,250)
◊ 2021-06-07 15:02:28.159592 B0#3160 B [B0_2] -> [C0_3] C transfer [5,0] (0)
transfer_48 te $amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ Su abcd " - 202 bytes
(modified settled 0.006622 S)
◊ 2021-06-07 15:02:28.159730 C0#3193 C [C0_3] <- [B0_2] B transfer [5,0] (0)
transfer_48 te $amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ Su abcd " - 202 bytes
(modified settled 0.006390 S)
◊ 2021-06-07 15:02:28.160434 C0#3207 C [C0_3] -> [B0_2] B flow [5,0] (1,250)
◊ 2021-06-07 15:02:28.160598 B0#3162 B [B0_2] <- [C0_3] C flow [5,0] (1,250)
◊ 2021-06-07 15:02:28.160605 C0#3211 C [C0_2] -> [D0_2] D transfer [5,0] (0)
transfer_48 te $amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ Su abcd " - 207 bytes
(modified settled 0.005159 S)
◊ 2021-06-07 15:02:28.160734 D0#1903 D [D0_2] <- [C0_2] C transfer [5,0] (0)
transfer_48 te $amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ Su abcd " - 207 bytes
(modified settled 0.004955 S)
◊ 2021-06-07 15:02:28.161253 D0#1916 D [] [] TCP_ADAPTOR (debug) [C1]
qdr_tcp_activate: schedule activate_timer
◊ 2021-06-07 15:02:28.161399 D0#1917 D [D0_2] -> [C0_2] C flow [5,0] (1,250)
◊ 2021-06-07 15:02:28.161442 D0#1918 D [] [] TCP_ADAPTOR (debug) [C1]
on_activate
◊ 2021-06-07 15:02:28.161478 D0#1919 D [] [] TCP_ADAPTOR (debug) [C1][L1]
qdr_tcp_push
◊ 2021-06-07 15:02:28.161523 D0#1921 D [] [] TCP_ADAPTOR (debug) [C1][L1][D93]
qdr_tcp_deliver Delivery event
◊ 2021-06-07 15:02:28.161552 D0#1922 D [] [] TCP_ADAPTOR (debug) [C1][L1][D93]
tcp_adaptor initiating egress connection
◊ 2021-06-07 15:02:28.161553 C0#3213 C [C0_2] <- [D0_2] D flow [5,0] (1,250)
◊ 2021-06-07 15:02:28.161678 D0#1924 D [] [] TCP_ADAPTOR (info) [C3] call
pn_proactor_raw_connect(). Egress connecting to: 127.0.0.1:9090
◊ 2021-06-07 15:02:28.161930 D0#1927 D [] [] TCP_ADAPTOR (debug) [C1][L1]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.161961 D0#1928 D [] [] TCP_ADAPTOR (info) [C3]
PN_RAW_CONNECTION_CONNECTED Connector egress connected to 127.0.0.1:9090
◊ 2021-06-07 15:02:28.162000 D0#1929 D [] [] TCP_ADAPTOR (info) [C3] Opening
server-side core connection 127.0.0.1:9090
◊ 2021-06-07 15:02:28.162151 D0#1931 D [] [] TCP_ADAPTOR (debug) [C1][L1][D93]
initial_delivery ownership passed to [C3][L24][D93]
◊ 2021-06-07 15:02:28.162201 D0#1933 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_NEED_WRITE_BUFFERS connector
◊ 2021-06-07 15:02:28.162224 D0#1934 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_NEED_READ_BUFFERS connector
◊ 2021-06-07 15:02:28.162487 D0#1944 D [] [] TCP_ADAPTOR (debug) [C3]
qdr_tcp_activate: call pn_raw_connection_wake()
◊ 2021-06-07 15:02:28.162547 D0#1945 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_WAKE connector
◊ 2021-06-07 15:02:28.162573 D0#1946 D [] [] TCP_ADAPTOR (trace) [C3][L0]
handle_incoming PNRC_WAKE for connector connection. read_closed:F,
flow_enabled:F
◊ 2021-06-07 15:02:28.162594 D0#1947 D [] [] TCP_ADAPTOR (debug) [C3][L0]
Waiting for credit before initiating connector ingress stream message
◊ 2021-06-07 15:02:28.162614 D0#1948 D [] [] TCP_ADAPTOR (debug) [C3][L24]
(connector outgoing) qdr_tcp_second_attach
◊ 2021-06-07 15:02:28.162648 D0#1949 D [] [] TCP_ADAPTOR (debug) [C3][L24]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.162674 D0#1950 D [] [] TCP_ADAPTOR (debug) [C3][L24]
qdr_tcp_push
◊ 2021-06-07 15:02:28.162715 D0#1952 D [] [] TCP_ADAPTOR (debug)
[C3][L24][D93] qdr_tcp_deliver Delivery event
◊ 2021-06-07 15:02:28.162831 D0#1954 D [] [] TCP_ADAPTOR (debug) [C3][L25]
(connector incoming) Created link to amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ
◊ 2021-06-07 15:02:28.162870 D0#1955 D [] [] TCP_ADAPTOR (trace) [C3][L25]
handle_incoming qdr_tcp_deliver for connector connection. read_closed:F,
flow_enabled:F
◊ 2021-06-07 15:02:28.162891 D0#1956 D [] [] TCP_ADAPTOR (debug) [C3][L25]
Waiting for credit before initiating connector ingress stream message
◊ 2021-06-07 15:02:28.162967 D0#1957 D [] [] TCP_ADAPTOR (debug) [C3] Copying
buffer 1 of 1 with 5 bytes (total=5)
◊ 2021-06-07 15:02:28.162988 D0#1958 D [] [] TCP_ADAPTOR (debug) [C3] Copied 1
buffers, 0 remain
◊ 2021-06-07 15:02:28.163008 D0#1959 D [] [] TCP_ADAPTOR (info) [C3] EOS
◊ 2021-06-07 15:02:28.163031 D0#1960 D [] [] TCP_ADAPTOR (debug) [C3]
pn_raw_connection_write_buffers wrote 5 bytes
◊ 2021-06-07 15:02:28.163050 D0#1961 D [] [] TCP_ADAPTOR (debug) [C3]
handle_outgoing calling pn_raw_connection_write_close(). rcv_complete:T,
send_complete:T
◊ 2021-06-07 15:02:28.163116 D0#1964 D [] [] TCP_ADAPTOR (debug) [C3][L24]
qdr_tcp_offer: NOOP
◊ 2021-06-07 15:02:28.163152 D0#1965 D [] [] TCP_ADAPTOR (debug) [C3][L24]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.163181 D0#1966 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_CLOSED_WRITE connector
◊ 2021-06-07 15:02:28.163405 D0#1969 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_WRITTEN connector pn_raw_connection_take_written_buffers
wrote 5 bytes. Total written 5 bytes
◊ 2021-06-07 15:02:28.163693 D0#1977 D [] [] TCP_ADAPTOR (debug) [C3]
qdr_add_tcp_connection_CT 127.0.0.1:9090 (1)
◊ 2021-06-07 15:02:28.163710 D0#1978 D [] [] TCP_ADAPTOR (debug) [C3]
qdr_tcp_activate: call pn_raw_connection_wake()
◊ 2021-06-07 15:02:28.163771 D0#1981 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_WAKE connector
◊ 2021-06-07 15:02:28.163835 D0#1983 D [] [] TCP_ADAPTOR (trace) [C3][L25]
handle_incoming PNRC_WAKE for connector connection. read_closed:F,
flow_enabled:F
◊ 2021-06-07 15:02:28.163881 D0#1985 D [] [] TCP_ADAPTOR (debug) [C3][L25]
Waiting for credit before initiating connector ingress stream message
◊ 2021-06-07 15:02:28.163915 D0#1986 D [] [] TCP_ADAPTOR (debug) [C3][L25]
(connector incoming) qdr_tcp_second_attach
◊ 2021-06-07 15:02:28.163945 D0#1987 D [] [] TCP_ADAPTOR (debug) [C3][L24]
qdr_tcp_flow: Flow enabled, credit=250
◊ 2021-06-07 15:02:28.163969 D0#1988 D [] [] TCP_ADAPTOR (trace) [C3][L25]
handle_incoming qdr_tcp_flow for connector connection. read_closed:F,
flow_enabled:T
◊ 2021-06-07 15:02:28.164038 D0#1989 D [] [] TCP_ADAPTOR (debug) [C3][L25]
Initiating connector (egress) stream incoming link for connection to:
amqp:/_topo/0/A/temp.bTHIFNg2iVyPevJ
◊ 2021-06-07 15:02:28.164153 D0#1993 D [] [] TCP_ADAPTOR (debug)
[C3][L25][D94] Initiating empty connector incoming stream message
◊ 2021-06-07 15:02:28.164165 D0#1994 D [] [] TCP_ADAPTOR (debug) [C3][L25]
Calling pn_raw_connection_give_read_buffers() capacity=32768 offset=0
◊ 2021-06-07 15:02:28.164179 D0#1995 D [] [] TCP_ADAPTOR (debug) [C3][L25]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.164248 D0#1998 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_READ connector Event
◊ 2021-06-07 15:02:28.164310 D0#2000 D [] [] TCP_ADAPTOR (trace) [C3][L25]
handle_incoming PNRC_READ for connector connection. read_closed:F,
flow_enabled:T
◊ 2021-06-07 15:02:28.164343 D0#2001 D [D0_2] -> [C0_2] C disposition [5]
(receiver 0-0 settled=false state=received)
◊ 2021-06-07 15:02:28.164361 D0#2002 D [] [] TCP_ADAPTOR (debug) [C3]
pn_raw_connection_take_read_buffers() took buffer with 21 bytes
◊ 2021-06-07 15:02:28.164412 D0#2004 D [] [] TCP_ADAPTOR (debug) [C3][L25]
Calling pn_raw_connection_give_read_buffers() capacity=32768 offset=0
◊ 2021-06-07 15:02:28.164462 C0#3215 C [C0_2] <- [D0_2] D disposition [5]
(receiver 0-0 settled=false state=received)
◊ 2021-06-07 15:02:28.164485 D0#2006 D [] [] TCP_ADAPTOR (trace)
[C3][L25][D94] Continuing connector message with 21 bytes
◊ 2021-06-07 15:02:28.164511 D0#2007 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_READ Read 21 bytes. Total read 21 bytes
◊ 2021-06-07 15:02:28.164596 D0#2010 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_READ connector Event
◊ 2021-06-07 15:02:28.164630 D0#2012 D [] [] TCP_ADAPTOR (trace) [C3][L25]
handle_incoming PNRC_READ for connector connection. read_closed:F,
flow_enabled:T
◊ 2021-06-07 15:02:28.164658 D0#2014 D [] [] TCP_ADAPTOR (debug) [C3]
pn_raw_connection_take_read_buffers() took buffer with 0 bytes
◊ 2021-06-07 15:02:28.164687 D0#2016 D [] [] TCP_ADAPTOR (debug) [C3][L25]
Calling pn_raw_connection_give_read_buffers() capacity=32768 offset=0
◊ 2021-06-07 15:02:28.164710 D0#2018 D [] [] TCP_ADAPTOR (debug) [C3]
PN_RAW_CONNECTION_READ Read 0 bytes. Total read 21 bytes
◊ 2021-06-07 15:02:28.164737 D0#2020 D [] [] TCP_ADAPTOR (debug) [C3][L25]
PN_RAW_CONNECTION_CLOSED_READ connector
◊ 2021-06-07 15:02:28.164773 D0#2022 D [] [] TCP_ADAPTOR (trace) [C3][L25]
handle_incoming PNRC_CLOSED_READ for connector connection. read_closed:T,
flow_enabled:T
◊ 2021-06-07 15:02:28.164805 D0#2023 D [] [] TCP_ADAPTOR (debug)
[C3][L25][D94] close connector instream delivery
◊ 2021-06-07 15:02:28.164860 C0#3223 C [C0_3] -> [B0_2] B disposition [5]
(receiver 0-0 settled=false state=received)
◊ 2021-06-07 15:02:28.164884 D0#2026 D [] [] TCP_ADAPTOR (info) [C3]
PN_RAW_CONNECTION_DISCONNECTED connector
◊ 2021-06-07 15:02:28.164920 D0#2028 D [D0_2] -> [C0_2] C begin [11,None)]
◊ 2021-06-07 15:02:28.164944 D0#2029 D [] [] TCP_ADAPTOR (debug) [C3][L25]
handle_disconnected - close instream
◊ 2021-06-07 15:02:28.164977 D0#2031 D [D0_2] -> [C0_2] C attach [11,0] sender
link_67 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.164987 B0#3164 B [B0_2] <- [C0_3] C disposition [5]
(receiver 0-0 settled=false state=received)
◊ 2021-06-07 15:02:28.165021 D0#2034 D [] [] TCP_ADAPTOR (debug) [C3][L24]
handle_disconnected - close outstream
◊ 2021-06-07 15:02:28.165068 C0#3224 C [C0_2] <- [D0_2] D begin [11,None)]
◊ 2021-06-07 15:02:28.165073 D0#2037 D [] [] TCP_ADAPTOR (debug) [C3][L25]
handle_disconnected - detach incoming
◊ 2021-06-07 15:02:28.165093 D0#2038 D [] [] TCP_ADAPTOR (debug) [C3][L24]
handle_disconnected - detach outgoing
◊ 2021-06-07 15:02:28.165139 C0#3225 C [C0_2] <- [D0_2] D attach [11,0] sender
link_67 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.165342 B0#3172 B [B0_1] -> [A0_1] A disposition [11]
(receiver 0-0 settled=false state=received)
◊ 2021-06-07 15:02:28.165403 C0#3227 C [C0_2] -> [D0_2] D begin [11,11]
◊ 2021-06-07 15:02:28.165451 A0#1907 A [A0_1] <- [B0_1] B disposition [11]
(receiver 0-0 settled=false state=received)
◊ 2021-06-07 15:02:28.165486 D0#2056 D [D0_2] <- [C0_2] C begin [11,11]
◊ 2021-06-07 15:02:28.165542 C0#3229 C [C0_2] -> [D0_2] D attach [11,0]
receiver link_67 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.165569 D0#2059 D [] [] TCP_ADAPTOR (debug) [C3]
qdr_del_tcp_connection_CT 127.0.0.1:9090 deleted. bytes_in=21, bytes_out=5,
opened_time=25, last_in_time=25, last_out_time=25. Connections remaining 0
◊ 2021-06-07 15:02:28.165598 C0#3230 C [C0_2] -> [D0_2] D flow [11,0] (0,250)
◊ 2021-06-07 15:02:28.165689 D0#2063 D [D0_2] -> [C0_2] C disposition [5]
(receiver 0-0 settled=true state=modified)
◊ 2021-06-07 15:02:28.165709 A0#1914 A [] [] TCP_ADAPTOR (debug) [C2]
qdr_tcp_activate: call pn_raw_connection_wake()
◊ 2021-06-07 15:02:28.165764 C0#3231 C [C0_2] <- [D0_2] D disposition [5]
(receiver 0-0 settled=true state=modified)
◊ 2021-06-07 15:02:28.165783 A0#1915 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_WAKE listener
◊ 2021-06-07 15:02:28.165795 D0#2065 D [D0_2] <- [C0_2] C attach [11,0]
receiver link_67 (source: none, target: none, class: router-data)
◊ 2021-06-07 15:02:28.165803 A0#1916 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_WAKE for listener connection. read_closed:T, flow_enabled:T
◊ 2021-06-07 15:02:28.165815 A0#1917 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_WAKE for listener connection. drain read buffers
◊ 2021-06-07 15:02:28.165828 A0#1918 A [] [] TCP_ADAPTOR (debug)
[C2][L24][D88] qdr_tcp_delivery_update: disp: 35, settled: false
◊ 2021-06-07 15:02:28.165833 D0#2066 D [D0_2] <- [C0_2] C flow [11,0] (0,250)
◊ 2021-06-07 15:02:28.165841 A0#1919 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming TCP RX window refresh for listener connection. read_closed:T,
flow_enabled:T
◊ 2021-06-07 15:02:28.165852 A0#1920 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming TCP RX window refresh for listener connection. drain read
buffers
◊ 2021-06-07 15:02:28.165874 A0#1922 A [] [] TCP_ADAPTOR (debug) [C2][L24]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.166054 D0#2073 D [D0_2] -> [C0_2] C transfer [11,0] (0)
transfer_49 J 127.0.0.1:44294@mySite@ Su d6a1bcfb355b : ABCD " - 190 bytes
(released settled 0.003235 S)
◊ 2021-06-07 15:02:28.166120 C0#3247 C [C0_3] -> [B0_2] B disposition [5]
(receiver 0-0 settled=true state=modified)
◊ 2021-06-07 15:02:28.166214 B0#3173 B [B0_2] <- [C0_3] C disposition [5]
(receiver 0-0 settled=true state=modified)
◊ 2021-06-07 15:02:28.166228 C0#3250 C [C0_2] <- [D0_2] D transfer [11,0] (0)
transfer_49 J 127.0.0.1:44294@mySite@ Su d6a1bcfb355b : ABCD " - 190 bytes
(released settled 0.002981 S)
◊ 2021-06-07 15:02:28.166610 B0#3190 B [B0_1] -> [A0_1] A disposition [11]
(receiver 0-0 settled=true state=modified)
◊ 2021-06-07 15:02:28.166688 C0#3263 C [C0_2] -> [D0_2] D flow [11,0] (1,250)
◊ 2021-06-07 15:02:28.166782 A0#1923 A [A0_1] <- [B0_1] B disposition [11]
(receiver 0-0 settled=true state=modified)
◊ 2021-06-07 15:02:28.166789 D0#2075 D [D0_2] <- [C0_2] C flow [11,0] (1,250)
◊ 2021-06-07 15:02:28.166864 C0#3268 C [C0_3] -> [B0_2] B transfer [5,1] (0)
transfer_49 J 127.0.0.1:44294@mySite@ Su d6a1bcfb355b : ABCD " - 215 bytes
(released settled 0.002014 S)
◊ 2021-06-07 15:02:28.167029 A0#1937 A [] [] TCP_ADAPTOR (debug) [C2]
qdr_tcp_activate: call pn_raw_connection_wake()
◊ 2021-06-07 15:02:28.167036 B0#3192 B [B0_2] <- [C0_3] C transfer [5,1] (0)
transfer_49 J 127.0.0.1:44294@mySite@ Su d6a1bcfb355b : ABCD " - 215 bytes
(released settled 0.001770 S)
◊ 2021-06-07 15:02:28.167089 A0#1938 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_WAKE listener
◊ 2021-06-07 15:02:28.167108 A0#1939 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_WAKE for listener connection. read_closed:T, flow_enabled:T
◊ 2021-06-07 15:02:28.167120 A0#1940 A [] [] TCP_ADAPTOR (trace) [C2][L24]
handle_incoming PNRC_WAKE for listener connection. drain read buffers
◊ 2021-06-07 15:02:28.167132 A0#1941 A [] [] TCP_ADAPTOR (debug)
[C2][L24][D88] qdr_tcp_delivery_update: disp: 39, settled: true
◊ 2021-06-07 15:02:28.167143 A0#1942 A [] [] TCP_ADAPTOR (debug)
[C2][L24][D88] qdr_tcp_delivery_update: call pn_raw_connection_close()
◊ 2021-06-07 15:02:28.167168 A0#1944 A [] [] TCP_ADAPTOR (debug) [C2][L24]
qdr_tcp_flow: No action. enabled:T, credit:1
◊ 2021-06-07 15:02:28.167179 A0#1945 A [] [] TCP_ADAPTOR (debug) [C2][L24]
qdr_tcp_get_credit: NOOP
◊ 2021-06-07 15:02:28.167193 A0#1946 A [] [] TCP_ADAPTOR (debug) [C2]
PN_RAW_CONNECTION_CLOSED_WRITE listener
◊ 2021-06-07 15:02:28.167205 A0#1947 A [] [] TCP_ADAPTOR (info) [C2]
PN_RAW_CONNECTION_DISCONNECTED listener
◊ 2021-06-07 15:02:28.167215 A0#1948 A [] [] TCP_ADAPTOR (debug) [C2][L24]
handle_disconnected - close instream
◊ 2021-06-07 15:02:28.167290 A0#1952 A [] [] TCP_ADAPTOR (debug) [C2][L24]
handle_disconnected - detach incoming
◊ 2021-06-07 15:02:28.167334 A0#1954 A [] [] TCP_ADAPTOR (debug) [C2][L23]
handle_disconnected - detach outgoing
◊ 2021-06-07 15:02:28.167502 B0#3206 B [B0_2] -> [C0_3] C flow [5,1] (1,250)
◊ 2021-06-07 15:02:28.167524 A0#1963 A [] [] TCP_ADAPTOR (debug) [C2]
qdr_del_tcp_connection_CT 0.0.0.0:9999 deleted. bytes_in=5, bytes_out=0,
opened_time=25, last_in_time=25, last_out_time=0. Connections remaining 0
◊ 2021-06-07 15:02:28.167595 C0#3270 C [C0_3] <- [B0_2] B flow [5,1] (1,250)
◊ 2021-06-07 15:02:28.167655 B0#3210 B [B0_1] -> [A0_1] A transfer [5,1] (0)
transfer_49 J 127.0.0.1:44294@mySite@ Su d6a1bcfb355b : ABCD " - 220 bytes
(released settled 0.000743 S)
◊ 2021-06-07 15:02:28.167786 A0#1964 A [A0_1] <- [B0_1] B transfer [5,1] (0)
transfer_49 J 127.0.0.1:44294@mySite@ Su d6a1bcfb355b : ABCD " - 220 bytes
(released settled 0.000515 S)
◊ 2021-06-07 15:02:28.168270 A0#1977 A [A0_1] -> [B0_1] B flow [5,1] (1,250)
◊ 2021-06-07 15:02:28.168301 A0#1979 A [A0_1] -> [B0_1] B disposition [5]
(receiver 0-0 settled=true state=released)
◊ 2021-06-07 15:02:28.168371 B0#3212 B [B0_1] <- [A0_1] A flow [5,1] (1,250)
◊ 2021-06-07 15:02:28.168398 B0#3213 B [B0_1] <- [A0_1] A disposition [5]
(receiver 0-0 settled=true state=released)
◊ 2021-06-07 15:02:28.168806 B0#3230 B [B0_2] -> [C0_3] C disposition [5]
(receiver 0-0 settled=true state=released)
◊ 2021-06-07 15:02:28.168878 C0#3272 C [C0_3] <- [B0_2] B disposition [5]
(receiver 0-0 settled=true state=released)
◊ 2021-06-07 15:02:28.169209 C0#3288 C [C0_2] -> [D0_2] D disposition [11]
(receiver 0-0 settled=true state=released)
◊ 2021-06-07 15:02:28.169289 D0#2077 D [D0_2] <- [C0_2] C disposition [11]
(receiver 0-0 settled=true state=released)
{code}
> Client app not getting a response through tcpListener
> -----------------------------------------------------
>
> Key: DISPATCH-1878
> URL: https://issues.apache.org/jira/browse/DISPATCH-1878
> Project: Qpid Dispatch
> Issue Type: Bug
> Components: Protocol Adaptors
> Affects Versions: 2.0.0
> Reporter: Fernando Giorgetti
> Assignee: Charles E. Rolke
> Priority: Major
> Fix For: 1.16.0
>
> Attachments: D-1878 @e148c.svg,
> DISPATCH-1878_fail-pn-raw-closed-before-ingress-stream-set-up.svg,
> dispatch-1878-trace.html
>
>
> I have a tcp-echo server running locally through:
> podman run -d --rm --name tcpecho -p 9090:9090 quay.io/skupper/tcp-go-echo
> And I have a router configured with a tcpConnector to localhost at port 9090
> and a tcpListener at port 9999.
> I am able to use nc (netcat) to send data to the tcp-echo directly (port
> 9090) or through
> the router (port 9999), if I run "nc 127.0.0.1 9999" and the send the data.
> But if I run it as: "echo abcd | nc 127.0.0.1 9999" I am not seeing a
> response, but if
> I use port 9090 (original port) instead of 9999 (router tcpListener), then I
> get the
> response correctly.
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
