I am using N3FJP AC Log which has rig control. I set up various versions (2.7.0 and 2.8.0_improved) of WSJT-X to use the N3FJP AC Log rig definition. Radios tested are IC-905, IC-705 (with a transverter aware band decoder) and Elecraft K3. The later 2 have transverters on 903 and 1296 and they report the transverter's frequency over CAT.
Focusing on just the K3 or IC-905, when any band below 1GHz is selected, all works as it should, with or without WSJT-X running. Change bands by any means (radio or WSJTX) to any band higher than 903, WSJT-X reads the frequency as only the 1GHz digit times 1,000,000. For example. 1296 results in 1.000,000Hz. 2304.100.000 results in 2.000MHz 5760.1 results in 5.000MHz After WSJTZX now think the frequency is 1.000Mhz (for 1296) it thinks a band change occurredon the WSJT-X side and sends 1.0MHz to AC Log where it is updated and AC Log in turn changes the radio to 1.000Mhz. I turned on WSJT_X Rig Control logging and got a narrowed log that I think shows the problem is in the WSJT-X hamlib code. I tried WSJT-X V2.8.0_improved today, same issue with frequencies above >999Mhz. In this attached log, I have the K3 set to 1296.171.100. AC Log is controlling the rig. All is good so far. I then start up WSJT-X. It queries AC Log for freq and gets 1296. Has correct band (23) and mode (SSB). string='<CMD><READBMFRESPONSE><BAND>23</BAND><MODE>SSB</MODE><MODETEST>PH</MODETEST><FREQ>1,296.171100</FREQ></CMD> The next thing is it extracts the frequency from the message wrong [2025-04-17 16:32:23.578614][00:00:02.941014][RIGCTRL:trace] aclog_get_freq: freq=1000000 Now WSJTX thinks it is 1.000MHz. It polls AC Log a bit more, it still gets 1296 as proper. Then it decided the frequency has changed and sends 1.000MHz to AC Log. Now AC log says 1.000055MHz. [2025-04-17 16:32:23.585489][00:00:02.951073][RIGCTRL:trace] aclog_set_freq: vfo=VFOA freq=1000055 [2025-04-17 16:32:23.585489][00:00:02.951193][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 43 48 41 4e 47 45 46 52 45 51 <CMD><CHANGEFREQ [2025-04-17 16:32:23.585489][00:00:02.951197][RIGCTRL:trace] 0010 3e 3c 56 41 4c 55 45 3e 31 2e 30 30 30 30 35 35 ><VALUE>1.000055 On the K3 I see 1.000.055 on the dial, then it immediately changes to 1.000.000. No idea why, step size is 10Hz on the radio. A log excerpt is pasted below, a full log attached if not stripped. Shutdown WSJT-X and AC Log frequency stays at 1.000.000. 73, Mike Lewis, K7MDL CN87xs __________________________ [2025-04-17 16:32:23.578614][00:00:02.940480][RIGCTRL:debug] network_flush called [2025-04-17 16:32:23.578614][00:00:02.940574][RIGCTRL:trace] write_block(): TX 22 bytes [2025-04-17 16:32:23.578614][00:00:02.940579][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 52 45 41 44 42 4d 46 3e 3c 2f <CMD><READBMF></ [2025-04-17 16:32:23.578614][00:00:02.940583][RIGCTRL:trace] 0010 43 4d 44 3e 0d 0a CMD>.. [2025-04-17 16:32:23.578614][00:00:02.940588][RIGCTRL:debug] *****5:aclog.c(247):write_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.940592][RIGCTRL:debug] *****5:aclog.c(141):read_transaction entered [2025-04-17 16:32:23.578614][00:00:02.940923][RIGCTRL:trace] read_string_generic(): RX 109 characters, direct=1 [2025-04-17 16:32:23.578614][00:00:02.940948][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 52 45 41 44 42 4d 46 52 45 53 <CMD><READBMFRES [2025-04-17 16:32:23.578614][00:00:02.940953][RIGCTRL:trace] 0010 50 4f 4e 53 45 3e 3c 42 41 4e 44 3e 32 33 3c 2f PONSE><BAND>23</ [2025-04-17 16:32:23.578614][00:00:02.940957][RIGCTRL:trace] 0020 42 41 4e 44 3e 3c 4d 4f 44 45 3e 53 53 42 3c 2f BAND><MODE>SSB</ [2025-04-17 16:32:23.578614][00:00:02.940960][RIGCTRL:trace] 0030 4d 4f 44 45 3e 3c 4d 4f 44 45 54 45 53 54 3e 50 MODE><MODETEST>P [2025-04-17 16:32:23.578614][00:00:02.940963][RIGCTRL:trace] 0040 48 3c 2f 4d 4f 44 45 54 45 53 54 3e 3c 46 52 45 H</MODETEST><FRE [2025-04-17 16:32:23.578614][00:00:02.940967][RIGCTRL:trace] 0050 51 3e 31 2c 32 39 36 2e 31 37 31 31 30 30 3c 2f Q>1,296.171100</ [2025-04-17 16:32:23.578614][00:00:02.940970][RIGCTRL:trace] 0060 46 52 45 51 3e 3c 2f 43 4d 44 3e 0d 0a FREQ></CMD>.. [2025-04-17 16:32:23.578614][00:00:02.940975][RIGCTRL:trace] read_transaction: string='<CMD><READBMFRESPONSE><BAND>23</BAND><MODE>SSB</MODE><MODETEST>PH</MODETEST><FREQ>1,296.171100</FREQ></CMD> ' [2025-04-17 16:32:23.578614][00:00:02.940983][RIGCTRL:trace] read_transaction: got </CMD> [2025-04-17 16:32:23.578614][00:00:02.940989][RIGCTRL:debug] *****5:aclog.c(208):read_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.940995][RIGCTRL:debug] ****4:aclog_transaction: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.941001][RIGCTRL:debug] ****4:aclog.c(311):aclog_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.941014][RIGCTRL:trace] aclog_get_freq: freq=1000000 [2025-04-17 16:32:23.578614][00:00:02.941022][RIGCTRL:debug] ***3:aclog.c(488):aclog_get_freq returning(0) [2025-04-17 16:32:23.578614][00:00:02.941028][RIGCTRL:trace] aclog_open: currvfo=VFOA value=<CMD><RIGRESPONSE><RIG>Elecraft</RIG><RIGINDEX>0</RIGINDEX></CMD> [2025-04-17 16:32:23.578614][00:00:02.941034][RIGCTRL:debug] **2:aclog.c(627):aclog_open returning(0) [2025-04-17 16:32:23.578614][00:00:02.941047][RIGCTRL:debug] **2:rig.c(3449):rig_get_vfo entered [2025-04-17 16:32:23.578614][00:00:02.941051][RIGCTRL:trace] rig_get_vfo: cache miss age=1000000ms [2025-04-17 16:32:23.578614][00:00:02.941055][RIGCTRL:trace] **rig.c(3485) trace [2025-04-17 16:32:23.578614][00:00:02.941060][RIGCTRL:debug] ***3:aclog.c(850):aclog_get_vfo entered [2025-04-17 16:32:23.578614][00:00:02.941065][RIGCTRL:debug] ***3:aclog.c(854):aclog_get_vfo returning(0) [2025-04-17 16:32:23.578614][00:00:02.941069][RIGCTRL:debug] **2:rig_get_vfo: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.941074][RIGCTRL:debug] **2:rig.c(3519):rig_get_vfo returning(0) [2025-04-17 16:32:23.578614][00:00:02.941087][RIGCTRL:debug] **2:rig.c(2433):rig_get_freq entered [2025-04-17 16:32:23.578614][00:00:02.941092][RIGCTRL:debug] rig_get_freq called vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941097][RIGCTRL:debug] rig_get_freq(2451) called vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941109][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:2458) vfo=VFOA, vfo_curr=VFOA, split=0 [2025-04-17 16:32:23.578614][00:00:02.941112][RIGCTRL:trace] vfo_fixup: final vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941115][RIGCTRL:debug] rig.c(2460) vfo=VFOA, curr_vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941128][RIGCTRL:trace] rig_get_freq: cache miss age=1000000ms, cached_vfo=VFOA, asked_vfo=VFOA, use_cached_freq=0 [2025-04-17 16:32:23.578614][00:00:02.941133][RIGCTRL:debug] rig_get_freq(2574): vfo_opt=0, model=8 [2025-04-17 16:32:23.578614][00:00:02.941137][RIGCTRL:debug] ***3:aclog.c(430):aclog_get_freq entered [2025-04-17 16:32:23.578614][00:00:02.941140][RIGCTRL:trace] aclog_get_freq: vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941145][RIGCTRL:debug] ****4:aclog.c(256):aclog_transaction entered [2025-04-17 16:32:23.578614][00:00:02.941148][RIGCTRL:debug] *****5:aclog.c(223):write_transaction entered [2025-04-17 16:32:23.578614][00:00:02.941151][RIGCTRL:debug] network_flush called [2025-04-17 16:32:23.578614][00:00:02.941190][RIGCTRL:trace] write_block(): TX 22 bytes [2025-04-17 16:32:23.578614][00:00:02.941195][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 52 45 41 44 42 4d 46 3e 3c 2f <CMD><READBMF></ [2025-04-17 16:32:23.578614][00:00:02.941198][RIGCTRL:trace] 0010 43 4d 44 3e 0d 0a CMD>.. [2025-04-17 16:32:23.578614][00:00:02.941203][RIGCTRL:debug] *****5:aclog.c(247):write_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.941206][RIGCTRL:debug] *****5:aclog.c(141):read_transaction entered [2025-04-17 16:32:23.578614][00:00:02.941618][RIGCTRL:trace] read_string_generic(): RX 109 characters, direct=1 [2025-04-17 16:32:23.578614][00:00:02.941633][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 52 45 41 44 42 4d 46 52 45 53 <CMD><READBMFRES [2025-04-17 16:32:23.578614][00:00:02.941637][RIGCTRL:trace] 0010 50 4f 4e 53 45 3e 3c 42 41 4e 44 3e 32 33 3c 2f PONSE><BAND>23</ [2025-04-17 16:32:23.578614][00:00:02.941640][RIGCTRL:trace] 0020 42 41 4e 44 3e 3c 4d 4f 44 45 3e 53 53 42 3c 2f BAND><MODE>SSB</ [2025-04-17 16:32:23.578614][00:00:02.941643][RIGCTRL:trace] 0030 4d 4f 44 45 3e 3c 4d 4f 44 45 54 45 53 54 3e 50 MODE><MODETEST>P [2025-04-17 16:32:23.578614][00:00:02.941646][RIGCTRL:trace] 0040 48 3c 2f 4d 4f 44 45 54 45 53 54 3e 3c 46 52 45 H</MODETEST><FRE [2025-04-17 16:32:23.578614][00:00:02.941649][RIGCTRL:trace] 0050 51 3e 31 2c 32 39 36 2e 31 37 31 31 30 30 3c 2f Q>1,296.171100</ [2025-04-17 16:32:23.578614][00:00:02.941662][RIGCTRL:trace] 0060 46 52 45 51 3e 3c 2f 43 4d 44 3e 0d 0a FREQ></CMD>.. [2025-04-17 16:32:23.578614][00:00:02.941668][RIGCTRL:trace] read_transaction: string='<CMD><READBMFRESPONSE><BAND>23</BAND><MODE>SSB</MODE><MODETEST>PH</MODETEST><FREQ>1,296.171100</FREQ></CMD> ' [2025-04-17 16:32:23.578614][00:00:02.941673][RIGCTRL:trace] read_transaction: got </CMD> [2025-04-17 16:32:23.578614][00:00:02.941679][RIGCTRL:debug] *****5:aclog.c(208):read_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.941685][RIGCTRL:debug] ****4:aclog_transaction: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.941689][RIGCTRL:debug] ****4:aclog.c(311):aclog_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.941698][RIGCTRL:trace] aclog_get_freq: freq=1000000 [2025-04-17 16:32:23.578614][00:00:02.941703][RIGCTRL:debug] ***3:aclog.c(488):aclog_get_freq returning(0) [2025-04-17 16:32:23.578614][00:00:02.941720][RIGCTRL:debug] rig_get_band called [2025-04-17 16:32:23.578614][00:00:02.941730][RIGCTRL:debug] rig_get_freq: band changing to BANDGEN [2025-04-17 16:32:23.578614][00:00:02.941737][RIGCTRL:debug] **2:rig_get_freq: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.941742][RIGCTRL:debug] **2:rig.c(2711):rig_get_freq returning(0) [2025-04-17 16:32:23.578614][00:00:02.941746][RIGCTRL:debug] **2:rig.c(2433):rig_get_freq entered [2025-04-17 16:32:23.578614][00:00:02.941750][RIGCTRL:debug] rig_get_freq called vfo=VFOB [2025-04-17 16:32:23.578614][00:00:02.941754][RIGCTRL:debug] rig_get_freq(2451) called vfo=VFOB [2025-04-17 16:32:23.578614][00:00:02.941761][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:2458) vfo=VFOB, vfo_curr=VFOA, split=0 [2025-04-17 16:32:23.578614][00:00:02.941764][RIGCTRL:trace] vfo_fixup: final vfo=VFOB [2025-04-17 16:32:23.578614][00:00:02.941768][RIGCTRL:debug] rig.c(2460) vfo=VFOB, curr_vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941780][RIGCTRL:trace] rig_get_freq: cache miss age=1000000ms, cached_vfo=VFOB, asked_vfo=VFOB, use_cached_freq=0 [2025-04-17 16:32:23.578614][00:00:02.941788][RIGCTRL:debug] rig_get_freq(2574): vfo_opt=0, model=8 [2025-04-17 16:32:23.578614][00:00:02.941792][RIGCTRL:debug] **2:rig_get_freq: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.941796][RIGCTRL:debug] **2:rig.c(2618):rig_get_freq returning(-11) Feature not available [2025-04-17 16:32:23.578614][00:00:02.941801][RIGCTRL:debug] **2:rig.c(5929):rig_get_split_vfo entered [2025-04-17 16:32:23.578614][00:00:02.941806][RIGCTRL:trace] rig_get_split_vfo: ?get_split_vfo=0 use_cache=0 [2025-04-17 16:32:23.578614][00:00:02.941809][RIGCTRL:debug] **2:rig_get_split_vfo: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.941812][RIGCTRL:debug] **2:rig.c(5956):rig_get_split_vfo returning(0) [2025-04-17 16:32:23.578614][00:00:02.941815][RIGCTRL:debug] rig_open(1564): Current split=0, tx_vfo=None [2025-04-17 16:32:23.578614][00:00:02.941820][RIGCTRL:debug] **2:rig.c(2976):rig_get_mode entered [2025-04-17 16:32:23.578614][00:00:02.941824][RIGCTRL:trace] vfo_fixup:(from rig_get_mode:2995) vfo=VFOA, vfo_curr=VFOA, split=0 [2025-04-17 16:32:23.578614][00:00:02.941827][RIGCTRL:trace] vfo_fixup: final vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941838][RIGCTRL:trace] rig_get_mode: VFOA cache check age=0ms [2025-04-17 16:32:23.578614][00:00:02.941844][RIGCTRL:trace] rig_get_mode: cache miss age mode=0ms, width=0ms [2025-04-17 16:32:23.578614][00:00:02.941848][RIGCTRL:trace] **rig.c(3045) trace [2025-04-17 16:32:23.578614][00:00:02.941853][RIGCTRL:debug] ***3:aclog.c(502):aclog_get_mode entered [2025-04-17 16:32:23.578614][00:00:02.941856][RIGCTRL:trace] aclog_get_mode: vfo=VFOA [2025-04-17 16:32:23.578614][00:00:02.941860][RIGCTRL:debug] ****4:aclog.c(256):aclog_transaction entered [2025-04-17 16:32:23.578614][00:00:02.941864][RIGCTRL:debug] *****5:aclog.c(223):write_transaction entered [2025-04-17 16:32:23.578614][00:00:02.941867][RIGCTRL:debug] network_flush called [2025-04-17 16:32:23.578614][00:00:02.941917][RIGCTRL:trace] write_block(): TX 22 bytes [2025-04-17 16:32:23.578614][00:00:02.941924][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 52 45 41 44 42 4d 46 3e 3c 2f <CMD><READBMF></ [2025-04-17 16:32:23.578614][00:00:02.941927][RIGCTRL:trace] 0010 43 4d 44 3e 0d 0a CMD>.. [2025-04-17 16:32:23.578614][00:00:02.941931][RIGCTRL:debug] *****5:aclog.c(247):write_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.941934][RIGCTRL:debug] *****5:aclog.c(141):read_transaction entered [2025-04-17 16:32:23.578614][00:00:02.942350][RIGCTRL:trace] read_string_generic(): RX 109 characters, direct=1 [2025-04-17 16:32:23.578614][00:00:02.942365][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 52 45 41 44 42 4d 46 52 45 53 <CMD><READBMFRES [2025-04-17 16:32:23.578614][00:00:02.942369][RIGCTRL:trace] 0010 50 4f 4e 53 45 3e 3c 42 41 4e 44 3e 32 33 3c 2f PONSE><BAND>23</ [2025-04-17 16:32:23.578614][00:00:02.942372][RIGCTRL:trace] 0020 42 41 4e 44 3e 3c 4d 4f 44 45 3e 53 53 42 3c 2f BAND><MODE>SSB</ [2025-04-17 16:32:23.578614][00:00:02.942375][RIGCTRL:trace] 0030 4d 4f 44 45 3e 3c 4d 4f 44 45 54 45 53 54 3e 50 MODE><MODETEST>P [2025-04-17 16:32:23.578614][00:00:02.942378][RIGCTRL:trace] 0040 48 3c 2f 4d 4f 44 45 54 45 53 54 3e 3c 46 52 45 H</MODETEST><FRE [2025-04-17 16:32:23.578614][00:00:02.942383][RIGCTRL:trace] 0050 51 3e 31 2c 32 39 36 2e 31 37 31 31 30 30 3c 2f Q>1,296.171100</ [2025-04-17 16:32:23.578614][00:00:02.942390][RIGCTRL:trace] 0060 46 52 45 51 3e 3c 2f 43 4d 44 3e 0d 0a FREQ></CMD>.. [2025-04-17 16:32:23.578614][00:00:02.942396][RIGCTRL:trace] read_transaction: string='<CMD><READBMFRESPONSE><BAND>23</BAND><MODE>SSB</MODE><MODETEST>PH</MODETEST><FREQ>1,296.171100</FREQ></CMD> ' [2025-04-17 16:32:23.578614][00:00:02.942401][RIGCTRL:trace] read_transaction: got </CMD> [2025-04-17 16:32:23.578614][00:00:02.942406][RIGCTRL:debug] *****5:aclog.c(208):read_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.942412][RIGCTRL:debug] ****4:aclog_transaction: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.942416][RIGCTRL:debug] ****4:aclog.c(311):aclog_transaction returning(0) [2025-04-17 16:32:23.578614][00:00:02.942427][RIGCTRL:trace] modeMapGetHamlib: find '|SSB|' in '|USB|' [2025-04-17 16:32:23.578614][00:00:02.942431][RIGCTRL:trace] modeMapGetHamlib: find '|SSB|' in '|SSB|' [2025-04-17 16:32:23.578614][00:00:02.942434][RIGCTRL:trace] aclog_get_mode: mode='USB' [2025-04-17 16:32:23.578614][00:00:02.942438][RIGCTRL:debug] ***3:aclog.c(556):aclog_get_mode returning(0) [2025-04-17 16:32:23.578614][00:00:02.942443][RIGCTRL:trace] rig_get_mode: retcode after get_mode=0 [2025-04-17 16:32:23.578614][00:00:02.942449][RIGCTRL:trace] rig_get_mode(3091): debug [2025-04-17 16:32:23.578614][00:00:02.942454][RIGCTRL:debug] ***3:cache.c(40):rig_set_cache_mode entered [2025-04-17 16:32:23.578614][00:00:02.942464][RIGCTRL:debug] ***3:cache.c(156):rig_set_cache_mode returning(0) [2025-04-17 16:32:23.578614][00:00:02.942470][RIGCTRL:debug] **2:rig_get_mode: elapsed=0ms [2025-04-17 16:32:23.578614][00:00:02.942475][RIGCTRL:debug] **2:rig.c(3108):rig_get_mode returning(0) [2025-04-17 16:32:23.578614][00:00:02.942479][RIGCTRL:debug] network_flush called [2025-04-17 16:32:23.578614][00:00:02.942486][RIGCTRL:debug] **2:network.c(1589):network_multicast_publisher_start entered [2025-04-17 16:32:23.578614][00:00:02.942490][RIGCTRL:debug] network.c(1598): multicast publisher address=0.0.0.0, port=4532 [2025-04-17 16:32:23.585489][00:00:02.950811][RIGCTRL:debug] is_networked: network IfType = 6, name=Ethernet [2025-04-17 16:32:23.585489][00:00:02.950836][RIGCTRL:debug] 192.168.2.65: Address: 192.168.2.65 [2025-04-17 16:32:23.585489][00:00:02.950843][RIGCTRL:trace] network.c(1616): not starting multicast publisher [2025-04-17 16:32:23.585489][00:00:02.950850][RIGCTRL:debug] **2:network.c(1618):network_multicast_publisher_start returning(0) [2025-04-17 16:32:23.585489][00:00:02.950861][RIGCTRL:debug] **2:network.c(1800):network_multicast_receiver_start entered [2025-04-17 16:32:23.585489][00:00:02.950865][RIGCTRL:debug] network.c(1810): multicast receiver address=0.0.0.0, port=4532 [2025-04-17 16:32:23.585489][00:00:02.950869][RIGCTRL:trace] network.c(1817): not starting multicast receiver [2025-04-17 16:32:23.585489][00:00:02.950878][RIGCTRL:debug] **2:network.c(1819):network_multicast_receiver_start returning(0) [2025-04-17 16:32:23.585489][00:00:02.950882][RIGCTRL:debug] **2:event.c(272):rig_poll_routine_start entered [2025-04-17 16:32:23.585489][00:00:02.950948][RIGCTRL:debug] **2:event.c(312):rig_poll_routine_start returning(0) [2025-04-17 16:32:23.585489][00:00:02.950955][RIGCTRL:debug] rig.c(288):add_opened_rig entered [2025-04-17 16:32:23.585489][00:00:02.950960][RIGCTRL:debug] rig.c(300):add_opened_rig returning2(0) [2025-04-17 16:32:23.585489][00:00:02.950964][RIGCTRL:debug] rig.c(1641):rig_open returning2(0) [2025-04-17 16:32:23.585489][00:00:02.950970][RIGCTRL:debug] **2:rig.c(3449):rig_get_vfo entered [2025-04-17 16:32:23.585489][00:00:02.950974][RIGCTRL:trace] rig_get_vfo: cache hit age=6ms, vfo=VFOA [2025-04-17 16:32:23.585489][00:00:02.950981][RIGCTRL:debug] **2:rig_get_vfo: elapsed=0ms [2025-04-17 16:32:23.585489][00:00:02.950985][RIGCTRL:debug] **2:rig.c(3478):rig_get_vfo returning(0) [2025-04-17 16:32:23.585489][00:00:02.950991][RIGCTRL:debug] **2:rig.c(2433):rig_get_freq entered [2025-04-17 16:32:23.585489][00:00:02.950995][RIGCTRL:debug] rig_get_freq called vfo=currVFO [2025-04-17 16:32:23.585489][00:00:02.950999][RIGCTRL:debug] rig_get_freq(2451) called vfo=currVFO [2025-04-17 16:32:23.585489][00:00:02.951008][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:2458) vfo=currVFO, vfo_curr=VFOA, split=0 [2025-04-17 16:32:23.585489][00:00:02.951011][RIGCTRL:trace] vfo_fixup: Leaving currVFO alone [2025-04-17 16:32:23.585489][00:00:02.951014][RIGCTRL:debug] rig.c(2460) vfo=currVFO, curr_vfo=VFOA [2025-04-17 16:32:23.585489][00:00:02.951027][RIGCTRL:trace] rig_get_freq: VFOA cache hit age=6ms, freq=1000000, use_cached_freq=0 [2025-04-17 16:32:23.585489][00:00:02.951031][RIGCTRL:debug] **2:rig_get_freq: elapsed=0ms [2025-04-17 16:32:23.585489][00:00:02.951034][RIGCTRL:debug] **2:rig.c(2554):rig_get_freq returning(0) [2025-04-17 16:32:23.585489][00:00:02.951044][RIGCTRL:debug] rig_get_band called [2025-04-17 16:32:23.585489][00:00:02.951048][RIGCTRL:debug] **2:rig.c(2102):rig_set_freq entered [2025-04-17 16:32:23.585489][00:00:02.951052][RIGCTRL:debug] rig_set_freq called vfo=currVFO, freq=1000055 [2025-04-17 16:32:23.585489][00:00:02.951057][RIGCTRL:trace] vfo_fixup:(from rig_set_freq:2179) vfo=currVFO, vfo_curr=VFOA, split=0 [2025-04-17 16:32:23.585489][00:00:02.951060][RIGCTRL:trace] vfo_fixup: Leaving currVFO alone [2025-04-17 16:32:23.585489][00:00:02.951063][RIGCTRL:debug] skip_freq: not skipping set_freq on vfo VFOA [2025-04-17 16:32:23.585489][00:00:02.951066][RIGCTRL:trace] rig_set_freq: TARGETABLE_FREQ vfo=VFOA [2025-04-17 16:32:23.585489][00:00:02.951069][RIGCTRL:trace] aclog_set_freq [2025-04-17 16:32:23.585489][00:00:02.951073][RIGCTRL:trace] aclog_set_freq: vfo=VFOA freq=1000055 [2025-04-17 16:32:23.585489][00:00:02.951079][RIGCTRL:debug] ***3:aclog.c(256):aclog_transaction entered [2025-04-17 16:32:23.585489][00:00:02.951084][RIGCTRL:debug] ****4:aclog.c(223):write_transaction entered [2025-04-17 16:32:23.585489][00:00:02.951087][RIGCTRL:debug] network_flush called [2025-04-17 16:32:23.585489][00:00:02.951142][RIGCTRL:debug] event.c(85): Starting rig poll routine thread [2025-04-17 16:32:23.585489][00:00:02.951182][RIGCTRL:trace] write_block(): TX 95 bytes [2025-04-17 16:32:23.585489][00:00:02.951193][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 43 48 41 4e 47 45 46 52 45 51 <CMD><CHANGEFREQ [2025-04-17 16:32:23.585489][00:00:02.951197][RIGCTRL:trace] 0010 3e 3c 56 41 4c 55 45 3e 31 2e 30 30 30 30 35 35 ><VALUE>1.000055 [2025-04-17 16:32:23.585489][00:00:02.951212][RIGCTRL:trace] rig_set_cache_timeout_ms: called selection=0, ms=1000 [2025-04-17 16:32:23.585489][00:00:02.951230][RIGCTRL:trace] 0020 3c 2f 56 41 4c 55 45 3e 3c 53 55 50 50 52 45 53 </VALUE><SUPPRES [2025-04-17 16:32:23.585489][00:00:02.951238][RIGCTRL:trace] 0030 53 4d 4f 44 45 44 45 46 41 55 4c 54 3e 54 52 55 SMODEDEFAULT>TRU [2025-04-17 16:32:23.585489][00:00:02.951242][RIGCTRL:trace] 0040 45 3c 2f 53 55 50 50 52 45 53 53 4d 4f 44 45 44 E</SUPPRESSMODED [2025-04-17 16:32:23.585489][00:00:02.951246][RIGCTRL:trace] 0050 45 46 41 55 4c 54 3e 3c 2f 43 4d 44 3e 0d 0a EFAULT></CMD>.. [2025-04-17 16:32:23.585489][00:00:02.951252][RIGCTRL:debug] ****4:aclog.c(247):write_transaction returning(0) [2025-04-17 16:32:23.585489][00:00:02.951256][RIGCTRL:debug] ****4:aclog.c(141):read_transaction entered [2025-04-17 16:32:23.585489][00:00:02.951652][RIGCTRL:trace] read_string_generic(): RX 56 characters, direct=1 [2025-04-17 16:32:23.585489][00:00:02.951668][RIGCTRL:trace] 0000 3c 43 4d 44 3e 3c 43 48 41 4e 47 45 46 52 45 51 <CMD><CHANGEFREQ [2025-04-17 16:32:23.585489][00:00:02.951673][RIGCTRL:trace] 0010 52 45 53 50 4f 4e 53 45 3e 3c 56 41 4c 55 45 3e RESPONSE><VALUE> [2025-04-17 16:32:23.585489][00:00:02.951677][RIGCTRL:trace] 0020 31 2e 30 30 30 30 35 35 3c 2f 56 41 4c 55 45 3e 1.000055</VALUE> [2025-04-17 16:32:23.585489][00:00:02.951681][RIGCTRL:trace] 0030 3c 2f 43 4d 44 3e 0d 0a </CMD>.. [2025-04-17 16:32:23.585489][00:00:02.951686][RIGCTRL:trace] read_transaction: string='<CMD><CHANGEFREQRESPONSE><VALUE>1.000055</VALUE></CMD> '
WSJT-X_RigControl.log
Description: WSJT-X_RigControl.log
_______________________________________________ wsjt-devel mailing list wsjt-devel@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/wsjt-devel
