I've determined the problem now is with HRD (running 6.2.72.293) and Windows
7 32-bit. So I'm going to report this to HRD. This behavior also shows
up during WSJT-X operations during frequency changes with very slow freq
changes (like 10 seconds for example). This is WSJT-X v1.4.0 r4270-dirty.
Now that the send cmd is time-synced with the network traffic in my patch
this is easier to correlate. I still have the 30 second timeout too at this
point since that makes it easier to see what's happening right now.
Here is a very clear example. AT 18:20:13 WSJT-X sends "get frequencies"
(note that the send message doesn't show up until AFTER the reply is
received in this trace log). After the ACK from HRD for that packet HRD
does not send the answer or any other packet for another 11 seconds at
18:24.
Meanwhile HRD Logger is happily chatting with HRD. What's of interest and
may explain why this sometimes works and sometimes not is that in this
example HRD Logger asks for frequencies, HRD acks, then WSJT-X asks for
frequencies before HRD replies to Logger. So this interplay may be what
causes the problem.
In this sequence port 49188 is WSJT-X and 49186 is HRD Logger
Here is the trace from WSJT-X -- I've included a bit after the packet
capture where you can see it recovers OK.
Tue Sep 9 04:20:13 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:762)Debug:
HRDTransceiver::send_command bytesAvailable= 60
Tue Sep 9 04:20:13 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:794)Debug:
HRDTransceiver::send_command: reply byte count: 60
Tue Sep 9 04:20:13 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:824)Debug:
HRDTransceiver::send_command( "get radio" ): -> "TT-OMNI VII (Radio)"
Tue Sep 9 04:20:24 2014"
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:762)Debug:
HRDTransceiver::send_command bytesAvailable= 52
Tue Sep 9 04:20:24 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:794)Debug:
HRDTransceiver::send_command: reply byte count: 52
Tue Sep 9 04:20:24 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:824)Debug:
HRDTransceiver::send_command( "get frequencies" ): -> "1838000-7043"
Tue Sep 9 04:20:46 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:762)Debug:
HRDTransceiver::send_command bytesAvailable= 60
Tue Sep 9 04:20:46 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:794)Debug:
HRDTransceiver::send_command: reply byte count: 60
Tue Sep 9 04:20:46 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:824)Debug:
HRDTransceiver::send_command( "get radio" ): -> "TT-OMNI VII (Radio)"
Tue Sep 9 04:20:48 2014
GMT(C:\JTSDK-QT\src\wsjtx\Configuration.cpp:502)Debug:
Configuration::transceiver_ptt: false Transceiver::TransceiverState(yes
Frequency {1838000Hz, 7043800Hz} USB; SPLIT: unknown; PTT: on)
Tue Sep 9 04:20:50 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:762)Debug:
HRDTransceiver::send_command bytesAvailable= 52
Tue Sep 9 04:20:50 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:794)Debug:
HRDTransceiver::send_command: reply byte count: 52
Tue Sep 9 04:20:50 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:824)Debug:
HRDTransceiver::send_command( "get frequencies" ): -> "1838000-7043"
Tue Sep 9 04:20:50 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:416)Debug:
HRDTransceiver::do_ptt: false
Tue Sep 9 04:20:50 2014
GMT(C:\JTSDK-QT\src\wsjtx\HamlibTransceiver.cpp:771)Debug:
HamlibTransceiver::do_ptt: false Transceiver::TransceiverState(oes Frequency
{0Hz, 0Hz} UNK; SPLIT: unknown; PTT: on) reversed = false
Tue Sep 9 04:20:56 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:762)Debug:
HRDTransceiver::send_command bytesAvailable= 60
Tue Sep 9 04:20:56 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:794)Debug:
HRDTransceiver::send_command: reply byte count: 60
Tue Sep 9 04:20:56 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:824)Debug:
HRDTransceiver::send_command( "get radio" ): -> "TT-OMNI VII (Radio)"
Tue Sep 9 04:21:00 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:762)Debug:
HRDTransceiver::send_command bytesAvailable= 52
Tue Sep 9 04:21:00 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:794)Debug:
HRDTransceiver::send_command: reply byte count: 52
Tue Sep 9 04:21:00 2014
GMT(C:\JTSDK-QT\src\wsjtx\HRDTransceiver.cpp:824)Debug:
HRDTransceiver::send_command( "get frequencies" ): -> "1838000-7043"
18:20:13.326445 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12382913:12382977(64)
ack 6151087 win 123
0x0000: 4500 0068 175e 4000 8006 0000 7f00 0001 E..h.^@.........
0x0010: 7f00 0001 c022 1e81 43b0 bafb d8dc 06ea ....."..C.......
0x0020: 5018 007b 3c75 0000 4000 0000 cdab 3412 P..{<u..@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:13.326445 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12382977 win 124
0x0000: 4500 0028 175f 4000 8006 0000 7f00 0001 E..(._@.........
0x0010: 7f00 0001 1e81 c022 d8dc 06ea 43b0 bb3b ......."....C..;
0x0020: 5010 007c f3ff 0000 P..|....
18:20:13.373450 IP 127.0.0.1.49188 > 127.0.0.1.7809: P 923233:923293(60) ack
974583 win 29
0x0000: 4500 0064 1760 4000 8006 0000 7f00 0001 E..d.`@.........
0x0010: 7f00 0001 c024 1e81 ecfa 50ef e082 742c .....$....P...t,
0x0020: 5018 001d 8cae 0000 3c00 0000 cdab 3412 P.......<.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 s...
18:20:13.373450 IP 127.0.0.1.7809 > 127.0.0.1.49188: . ack 923293 win 122
0x0000: 4500 0028 1761 4000 8006 0000 7f00 0001 E..(.a@.........
0x0010: 7f00 0001 1e81 c024 e082 742c ecfa 512b .......$..t,..Q+
0x0020: 5010 007a 3fdd 0000 P..z?...
18:20:15.519665 IP 127.0.0.1.7809 > 127.0.0.1.49186: P 6151087:6151139(52)
ack 12382977 win 124
0x0000: 4500 005c 1762 4000 8006 0000 7f00 0001 E..\.b@.........
0x0010: 7f00 0001 1e81 c022 d8dc 06ea 43b0 bb3b ......."....C..;
0x0020: 5018 007c c544 0000 3400 0000 cdab 3412 P..|.D..4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:15.519665 IP 127.0.0.1.49186 > 127.0.0.1.7809: . ack 6151139 win 123
0x0000: 4500 0028 1763 4000 8006 0000 7f00 0001 E..(.c@.........
0x0010: 7f00 0001 c022 1e81 43b0 bb3b d8dc 071e ....."..C..;....
0x0020: 5010 007b f3cc 0000 P..{....
18:20:15.521665 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12382977:12383041(64)
ack 6151139 win 123
0x0000: 4500 0068 1764 4000 8006 0000 7f00 0001 E..h.d@.........
0x0010: 7f00 0001 c022 1e81 43b0 bb3b d8dc 071e ....."..C..;....
0x0020: 5018 007b 3c01 0000 4000 0000 cdab 3412 P..{<...@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:15.521665 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12383041 win 124
0x0000: 4500 0028 1765 4000 8006 0000 7f00 0001 E..(.e@.........
0x0010: 7f00 0001 1e81 c022 d8dc 071e 43b0 bb7b ......."....C..{
0x0020: 5010 007c f38b 0000 P..|....
18:20:16.563769 IP 127.0.0.1.7809 > 127.0.0.1.49186: P 6151139:6151191(52)
ack 12383041 win 124
0x0000: 4500 005c 1768 4000 8006 0000 7f00 0001 E..\.h@.........
0x0010: 7f00 0001 1e81 c022 d8dc 071e 43b0 bb7b ......."....C..{
0x0020: 5018 007c c4d0 0000 3400 0000 cdab 3412 P..|....4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:16.563769 IP 127.0.0.1.49186 > 127.0.0.1.7809: . ack 6151191 win 123
0x0000: 4500 0028 1769 4000 8006 0000 7f00 0001 E..(.i@.........
0x0010: 7f00 0001 c022 1e81 43b0 bb7b d8dc 0752 ....."..C..{...R
0x0020: 5010 007b f358 0000 P..{.X..
18:20:16.563769 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12383041:12383105(64)
ack 6151191 win 123
0x0000: 4500 0068 176a 4000 8006 0000 7f00 0001 E..h.j@.........
0x0010: 7f00 0001 c022 1e81 43b0 bb7b d8dc 0752 ....."..C..{...R
0x0020: 5018 007b 3b8d 0000 4000 0000 cdab 3412 P..{;...@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:16.563769 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12383105 win 124
0x0000: 4500 0028 176b 4000 8006 0000 7f00 0001 E..(.k@.........
0x0010: 7f00 0001 1e81 c022 d8dc 0752 43b0 bbbb ......."...RC...
0x0020: 5010 007c f317 0000 P..|....
18:20:17.602873 IP 127.0.0.1.7809 > 127.0.0.1.49186: P 6151191:6151243(52)
ack 12383105 win 124
0x0000: 4500 005c 1770 4000 8006 0000 7f00 0001 E..\.p@.........
0x0010: 7f00 0001 1e81 c022 d8dc 0752 43b0 bbbb ......."...RC...
0x0020: 5018 007c c45c 0000 3400 0000 cdab 3412 P..|.\..4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:17.602873 IP 127.0.0.1.49186 > 127.0.0.1.7809: . ack 6151243 win 122
0x0000: 4500 0028 1771 4000 8006 0000 7f00 0001 E..(.q@.........
0x0010: 7f00 0001 c022 1e81 43b0 bbbb d8dc 0786 ....."..C.......
0x0020: 5010 007a f2e5 0000 P..z....
18:20:17.603873 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12383105:12383169(64)
ack 6151243 win 122
0x0000: 4500 0068 1772 4000 8006 0000 7f00 0001 E..h.r@.........
0x0010: 7f00 0001 c022 1e81 43b0 bbbb d8dc 0786 ....."..C.......
0x0020: 5018 007a 3b1a 0000 4000 0000 cdab 3412 P..z;...@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:17.603873 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12383169 win 124
0x0000: 4500 0028 1773 4000 8006 0000 7f00 0001 E..(.s@.........
0x0010: 7f00 0001 1e81 c022 d8dc 0786 43b0 bbfb ......."....C...
0x0020: 5010 007c f2a3 0000 P..|....
18:20:19.783091 IP 127.0.0.1.7809 > 127.0.0.1.49186: P 6151243:6151295(52)
ack 12383169 win 124
0x0000: 4500 005c 1778 4000 8006 0000 7f00 0001 E..\.x@.........
0x0010: 7f00 0001 1e81 c022 d8dc 0786 43b0 bbfb ......."....C...
0x0020: 5018 007c c3e8 0000 3400 0000 cdab 3412 P..|....4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:19.783091 IP 127.0.0.1.49186 > 127.0.0.1.7809: . ack 6151295 win 122
0x0000: 4500 0028 1779 4000 8006 0000 7f00 0001 E..(.y@.........
0x0010: 7f00 0001 c022 1e81 43b0 bbfb d8dc 07ba ....."..C.......
0x0020: 5010 007a f271 0000 P..z.q..
18:20:19.787091 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12383169:12383233(64)
ack 6151295 win 122
0x0000: 4500 0068 177a 4000 8006 0000 7f00 0001 E..h.z@.........
0x0010: 7f00 0001 c022 1e81 43b0 bbfb d8dc 07ba ....."..C.......
0x0020: 5018 007a 3aa6 0000 4000 0000 cdab 3412 P..z:...@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:19.788091 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12383233 win 123
0x0000: 4500 0028 177b 4000 8006 0000 7f00 0001 E..(.{@.........
0x0010: 7f00 0001 1e81 c022 d8dc 07ba 43b0 bc3b ......."....C..;
0x0020: 5010 007b f230 0000 P..{.0..
18:20:20.831196 IP 127.0.0.1.7809 > 127.0.0.1.49186: P 6151295:6151347(52)
ack 12383233 win 123
0x0000: 4500 005c 177e 4000 8006 0000 7f00 0001 E..\.~@.........
0x0010: 7f00 0001 1e81 c022 d8dc 07ba 43b0 bc3b ......."....C..;
0x0020: 5018 007b c375 0000 3400 0000 cdab 3412 P..{.u..4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:20.831196 IP 127.0.0.1.49186 > 127.0.0.1.7809: . ack 6151347 win 122
0x0000: 4500 0028 177f 4000 8006 0000 7f00 0001 E..(..@.........
0x0010: 7f00 0001 c022 1e81 43b0 bc3b d8dc 07ee ....."..C..;....
0x0020: 5010 007a f1fd 0000 P..z....
18:20:20.831196 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12383233:12383297(64)
ack 6151347 win 122
0x0000: 4500 0068 1780 4000 8006 0000 7f00 0001 E..h..@.........
0x0010: 7f00 0001 c022 1e81 43b0 bc3b d8dc 07ee ....."..C..;....
0x0020: 5018 007a 3a32 0000 4000 0000 cdab 3412 P..z:2..@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:20.831196 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12383297 win 123
0x0000: 4500 0028 1781 4000 8006 0000 7f00 0001 E..(..@.........
0x0010: 7f00 0001 1e81 c022 d8dc 07ee 43b0 bc7b ......."....C..{
0x0020: 5010 007b f1bc 0000 P..{....
18:20:21.870300 IP 127.0.0.1.7809 > 127.0.0.1.49186: P 6151347:6151399(52)
ack 12383297 win 123
0x0000: 4500 005c 1782 4000 8006 0000 7f00 0001 E..\..@.........
0x0010: 7f00 0001 1e81 c022 d8dc 07ee 43b0 bc7b ......."....C..{
0x0020: 5018 007b c301 0000 3400 0000 cdab 3412 P..{....4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:21.870300 IP 127.0.0.1.49186 > 127.0.0.1.7809: . ack 6151399 win 122
0x0000: 4500 0028 1783 4000 8006 0000 7f00 0001 E..(..@.........
0x0010: 7f00 0001 c022 1e81 43b0 bc7b d8dc 0822 ....."..C..{..."
0x0020: 5010 007a f189 0000 P..z....
18:20:21.871300 IP 127.0.0.1.49186 > 127.0.0.1.7809: P 12383297:12383361(64)
ack 6151399 win 122
0x0000: 4500 0068 1784 4000 8006 0000 7f00 0001 E..h..@.........
0x0010: 7f00 0001 c022 1e81 43b0 bc7b d8dc 0822 ....."..C..{..."
0x0020: 5018 007a 39be 0000 4000 0000 cdab 3412 P..z9...@.....4.
0x0030: 3412 cdab 0000 0000 5b00 3700 3800 3300 4.......[.7.8.3.
0x0040: 5d00 2000 6700 6500 7400 2000 6600 7200 ]...g.e.t...f.r.
0x0050: 6500 7100 7500 6500 6e00 6300 6900 6500 e.q.u.e.n.c.i.e.
0x0060: 7300 0000 0000 0000 s.......
18:20:21.871300 IP 127.0.0.1.7809 > 127.0.0.1.49186: . ack 12383361 win 123
0x0000: 4500 0028 1785 4000 8006 0000 7f00 0001 E..(..@.........
0x0010: 7f00 0001 1e81 c022 d8dc 0822 43b0 bcbb ......."..."C...
0x0020: 5010 007b f148 0000 P..{.H..
18:20:24.031516 IP 127.0.0.1.7809 > 127.0.0.1.49188: P 974583:974635(52) ack
923293 win 122
0x0000: 4500 005c 178a 4000 8006 0000 7f00 0001 E..\..@.........
0x0010: 7f00 0001 1e81 c024 e082 742c ecfa 512b .......$..t,..Q+
0x0020: 5018 007a 1122 0000 3400 0000 cdab 3412 P..z."..4.....4.
0x0030: 3412 cdab 0000 0000 3100 3800 3300 3800 4.......1.8.3.8.
0x0040: 3000 3000 3000 2d00 3700 3000 3400 3300 0.0.0.-.7.0.4.3.
0x0050: 3800 3000 3000 0000 0000 0000 8.0.0.......
18:20:24.031516 IP 127.0.0.1.49188 > 127.0.0.1.7809: . ack 974635 win 28
0x0000: 4500 0028 178b 4000 8006 0000 7f00 0001 E..(..@.........
0x0010: 7f00 0001 c024 1e81 ecfa 512b e082 7460 .....$....Q+..t`
0x0020: 5010 001c 4007 0000 P...@...
------------------------------------------------------------------------------
Want excitement?
Manually upgrade your production database.
When you want reliability, choose Perforce.
Perforce version control. Predictably reliable.
http://pubads.g.doubleclick.net/gampad/clk?id=157508191&iu=/4140/ostg.clktrk
_______________________________________________
wsjt-devel mailing list
wsjt-devel@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/wsjt-devel
