FT1000MP split VFOB frequency problem #819
Labels
bug
critical
A problem for common operations with WSJT-X, GPredict, RigPi, etc.
JTDX
Bugs affecting JTDX operations
needs test
Patches have been submitted but need testing to close issue
WSJTX
Bugs affecting WSTJ-X operations
using Hamlib 4.3.1 with WSJT-X, it appears that it is not possible to set the Tx VFO frequency using rig_set_split_freq_mode() on the above rig. The trace shows it being correctly set (elapsed time 00:00:09.915978) then before the API call returns it gets set to another frequency (elapsed time 00:00:09.915978). There also seems to be a considerable amount of other unnecessary CAT traffic! Here's the trace output for the API call:
[2021-09-29 15:10:00.823312][00:00:09.634867][RIGCTRL:trace] rig_set_split_freq_mode freq=3576000 mode = USB
[2021-09-29 15:10:00.823312][00:00:09.634912][RIGCTRL:debug] rig.c(4363):rig_set_split_freq_mode entered
[2021-09-29 15:10:00.823312][00:00:09.634939][RIGCTRL:trace] vfo_fixup: vfo=TX, vfo_curr=VFOA
[2021-09-29 15:10:00.823312][00:00:09.634966][RIGCTRL:trace] vfo_fixup: RIG_VFO_TX changed to VFOB, split=1, satmode=0
[2021-09-29 15:10:00.823312][00:00:09.635010][RIGCTRL:debug] rig_set_split_freq_mode: vfo=VFOB, tx_freq=3576000, tx_mode=USB, tx_width=-1
[2021-09-29 15:10:00.823312][00:00:09.635036][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4430) trace
[2021-09-29 15:10:00.823312][00:00:09.635061][RIGCTRL:debug] rig_set_split_freq called vfo=VFOB, curr_vfo=VFOA
[2021-09-29 15:10:00.823312][00:00:09.635084][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(3823) trace
[2021-09-29 15:10:00.823312][00:00:09.635109][RIGCTRL:debug] ft1000mp.c(1707):ft1000mp_set_split_freq entered
[2021-09-29 15:10:00.823312][00:00:09.635137][RIGCTRL:debug] rig.c(4536):rig_set_split_vfo entered
[2021-09-29 15:10:00.823312][00:00:09.635163][RIGCTRL:debug] rig_set_split_vfo: rx_vfo=VFOB, split=1, tx_vfo=VFOB
[2021-09-29 15:10:00.823312][00:00:09.635188][RIGCTRL:trace] vfo_fixup: vfo=VFOB, vfo_curr=VFOA
[2021-09-29 15:10:00.823312][00:00:09.635212][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2021-09-29 15:10:00.823312][00:00:09.635236][RIGCTRL:trace] vfo_fixup: vfo=VFOB, vfo_curr=VFOA
[2021-09-29 15:10:00.823312][00:00:09.635259][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2021-09-29 15:10:00.823312][00:00:09.635282][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4560) trace
[2021-09-29 15:10:00.823312][00:00:09.635305][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4594) trace
[2021-09-29 15:10:00.823312][00:00:09.635328][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4606) trace
[2021-09-29 15:10:00.823312][00:00:09.635352][RIGCTRL:debug] ft1000mp.c(1637):ft1000mp_set_split_vfo entered
[2021-09-29 15:10:00.823312][00:00:09.635376][RIGCTRL:trace] ft1000mp_set_split_vfo called rx_vfo=VFOB, tx_vfo=VFOB
[2021-09-29 15:10:00.823312][00:00:09.635401][RIGCTRL:debug] rig_get_freq(1994) called vfo=VFOB
[2021-09-29 15:10:00.823312][00:00:09.635449][RIGCTRL:trace] vfo_fixup: vfo=VFOB, vfo_curr=VFOA
[2021-09-29 15:10:00.823312][00:00:09.635472][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2021-09-29 15:10:00.823312][00:00:09.635498][RIGCTRL:debug] C:/Users/bill/src/hamlib/src/rig.c(2003) vfo=VFOB, curr_vfo=VFOA
[2021-09-29 15:10:00.824284][00:00:09.635594][RIGCTRL:trace] rig_get_freq: VFOB cache hit age=106ms, freq=14080000
[2021-09-29 15:10:00.824284][00:00:09.635622][RIGCTRL:debug] rig.c(2063):rig_get_freq return(0)
[2021-09-29 15:10:00.824284][00:00:09.635647][RIGCTRL:debug] ft1000mp.c(1608):ft1000mp_send_priv_cmd entered
[2021-09-29 15:10:00.824284][00:00:09.635670][RIGCTRL:trace] rig_flush: called for serial device
[2021-09-29 15:10:00.824284][00:00:09.635694][RIGCTRL:debug] serial.c(642):serial_flush entered
[2021-09-29 15:10:00.824284][00:00:09.635714][RIGCTRL:debug] tcflush
[2021-09-29 15:10:00.824284][00:00:09.635789][RIGCTRL:debug] serial.c(674):serial_flush return(0)
[2021-09-29 15:10:00.859499][00:00:09.670861][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:00.859499][00:00:09.670907][RIGCTRL:trace] 0000 00 00 00 00 85 .....
[2021-09-29 15:10:00.859499][00:00:09.670940][RIGCTRL:debug] ft1000mp.c(1626):ft1000mp_send_priv_cmd return(0)
[2021-09-29 15:10:00.859499][00:00:09.670966][RIGCTRL:debug] ft1000mp.c(1608):ft1000mp_send_priv_cmd entered
[2021-09-29 15:10:00.859499][00:00:09.670990][RIGCTRL:trace] rig_flush: called for serial device
[2021-09-29 15:10:00.859499][00:00:09.671015][RIGCTRL:debug] serial.c(642):serial_flush entered
[2021-09-29 15:10:00.859499][00:00:09.671036][RIGCTRL:debug] tcflush
[2021-09-29 15:10:00.859499][00:00:09.671110][RIGCTRL:debug] serial.c(674):serial_flush return(0)
[2021-09-29 15:10:00.894592][00:00:09.705987][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:00.894592][00:00:09.706033][RIGCTRL:trace] 0000 00 00 00 00 05 .....
[2021-09-29 15:10:00.894592][00:00:09.706062][RIGCTRL:debug] ft1000mp.c(1626):ft1000mp_send_priv_cmd return(0)
[2021-09-29 15:10:00.894592][00:00:09.706087][RIGCTRL:debug] ft1000mp.c(1608):ft1000mp_send_priv_cmd entered
[2021-09-29 15:10:00.894592][00:00:09.706114][RIGCTRL:trace] rig_flush: called for serial device
[2021-09-29 15:10:00.894592][00:00:09.706139][RIGCTRL:debug] serial.c(642):serial_flush entered
[2021-09-29 15:10:00.894592][00:00:09.706160][RIGCTRL:debug] tcflush
[2021-09-29 15:10:00.894592][00:00:09.706233][RIGCTRL:debug] serial.c(674):serial_flush return(0)
[2021-09-29 15:10:00.929825][00:00:09.741182][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:00.929825][00:00:09.741227][RIGCTRL:trace] 0000 00 00 00 01 01 .....
[2021-09-29 15:10:00.929825][00:00:09.741256][RIGCTRL:debug] ft1000mp.c(1626):ft1000mp_send_priv_cmd return(0)
[2021-09-29 15:10:00.929825][00:00:09.741294][RIGCTRL:debug] rig_set_freq called vfo=VFOB, freq=14080000
[2021-09-29 15:10:00.929825][00:00:09.741323][RIGCTRL:trace] vfo_fixup: vfo=VFOB, vfo_curr=VFOA
[2021-09-29 15:10:00.929825][00:00:09.741347][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2021-09-29 15:10:00.929825][00:00:09.741371][RIGCTRL:trace] rig_set_freq: TARGETABLE_FREQ vfo=VFOB
[2021-09-29 15:10:00.929825][00:00:09.741395][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(1842) trace
[2021-09-29 15:10:00.929825][00:00:09.741420][RIGCTRL:debug] ft1000mp.c(729):ft1000mp_set_freq entered
[2021-09-29 15:10:00.929825][00:00:09.741451][RIGCTRL:trace] ft1000mp_set_freq: requested freq = 14080000 Hz
[2021-09-29 15:10:00.929825][00:00:09.741475][RIGCTRL:debug] to_bcd called
[2021-09-29 15:10:00.929825][00:00:09.741502][RIGCTRL:debug] from_bcd called
[2021-09-29 15:10:00.929825][00:00:09.741526][RIGCTRL:debug] from_bcd called
[2021-09-29 15:10:00.929825][00:00:09.741544][RIGCTRL:trace] ft1000mp_set_freq: freq = 14080000 Hz
[2021-09-29 15:10:00.964960][00:00:09.776308][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:00.964960][00:00:09.776354][RIGCTRL:trace] 0000 00 80 40 01 8a ..@..
[2021-09-29 15:10:00.964960][00:00:09.776383][RIGCTRL:debug] ft1000mp.c(779):ft1000mp_set_freq return(0)
[2021-09-29 15:10:00.964960][00:00:09.776425][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(1950) trace
[2021-09-29 15:10:00.964960][00:00:09.776453][RIGCTRL:debug] rig.c(2616):rig_set_vfo entered
[2021-09-29 15:10:00.964960][00:00:09.776477][RIGCTRL:debug] rig_set_vfo called vfo=VFOA
[2021-09-29 15:10:00.964960][00:00:09.776502][RIGCTRL:trace] vfo_fixup: vfo=VFOA, vfo_curr=VFOA
[2021-09-29 15:10:00.964960][00:00:09.776526][RIGCTRL:trace] vfo_fixup: vfo=VFOA, vfo_curr=VFOA
[2021-09-29 15:10:00.964960][00:00:09.776550][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(2675) trace
[2021-09-29 15:10:00.964960][00:00:09.776575][RIGCTRL:debug] ft1000mp.c(1087):ft1000mp_set_vfo entered
[2021-09-29 15:10:00.964960][00:00:09.776598][RIGCTRL:trace] ft1000mp_set_vfo: called VFOA
[2021-09-29 15:10:00.964960][00:00:09.776623][RIGCTRL:debug] ft1000mp.c(1137):ft1000mp_set_vfo return(0)
[2021-09-29 15:10:00.964960][00:00:09.776647][RIGCTRL:trace] rig_set_vfo: rig->state.current_vfo=VFOA
[2021-09-29 15:10:00.964960][00:00:09.776670][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(2701) trace
[2021-09-29 15:10:00.964960][00:00:09.776692][RIGCTRL:debug] ft1000mp_get_freq: called
[2021-09-29 15:10:00.964960][00:00:09.776719][RIGCTRL:debug] ft1000mp.c(786):ft1000mp_get_vfo_data entered
[2021-09-29 15:10:00.964960][00:00:09.776751][RIGCTRL:debug] ft1000mp.c(1573):ft1000mp_get_update_data entered
[2021-09-29 15:10:00.964960][00:00:09.776776][RIGCTRL:debug] ft1000mp.c(1608):ft1000mp_send_priv_cmd entered
[2021-09-29 15:10:00.964960][00:00:09.776799][RIGCTRL:trace] rig_flush: called for serial device
[2021-09-29 15:10:00.964960][00:00:09.776824][RIGCTRL:debug] serial.c(642):serial_flush entered
[2021-09-29 15:10:00.964960][00:00:09.776848][RIGCTRL:debug] tcflush
[2021-09-29 15:10:00.964960][00:00:09.776922][RIGCTRL:debug] serial.c(674):serial_flush return(0)
[2021-09-29 15:10:01.000138][00:00:09.811459][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:01.000138][00:00:09.811505][RIGCTRL:trace] 0000 00 00 00 03 10 .....
[2021-09-29 15:10:01.000138][00:00:09.811534][RIGCTRL:debug] ft1000mp.c(1626):ft1000mp_send_priv_cmd return(0)
[2021-09-29 15:10:01.000138][00:00:09.811556][RIGCTRL:debug] read_block called
[2021-09-29 15:10:01.068478][00:00:09.879908][RIGCTRL:trace] read_block(): RX 32 bytes
[2021-09-29 15:10:01.068478][00:00:09.880013][RIGCTRL:trace] 0000 08 00 57 4e 00 00 00 01 11 00 11 b3 11 11 11 00 ..WN............
[2021-09-29 15:10:01.068478][00:00:09.880047][RIGCTRL:trace] 0010 11 01 57 c0 00 00 00 01 11 00 11 b3 11 11 11 00 ..W.............
[2021-09-29 15:10:01.068478][00:00:09.880077][RIGCTRL:debug] ft1000mp.c(1590):ft1000mp_get_update_data return(32)
[2021-09-29 15:10:01.068478][00:00:09.880103][RIGCTRL:debug] ft1000mp.c(805):ft1000mp_get_vfo_data return(32)
[2021-09-29 15:10:01.068478][00:00:09.880142][RIGCTRL:trace] ft1000mp_get_freq: freq = 3576000 Hz for VFO [1]
[2021-09-29 15:10:01.068478][00:00:09.880170][RIGCTRL:debug] ft1000mp.c(856):ft1000mp_get_freq return(0)
[2021-09-29 15:10:01.068478][00:00:09.880214][RIGCTRL:trace] rig_set_vfo: retcode from rig_get_freq = Command completed successfully
ft1000mp_get_freq: freq = 3576000 Hz for VFO [1]
ft1000mp.c(856):ft1000mp_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
ft1000mp_get_freq: freq = 3576000 Hz for VFO [1]
ft1000mp.c(856):ft1000mp_get_freq return(0)
ft1000mp.c(856):ft1000mp_get_freq return(0)
[2021-09-29 15:10:01.068478][00:00:09.880256][RIGCTRL:trace] rig_set_vfo: return 0, vfo=VFOA, curr_vfo=VFOA
[2021-09-29 15:10:01.068478][00:00:09.880283][RIGCTRL:debug] rig.c(2728):rig_set_vfo return(0)
[2021-09-29 15:10:01.068478][00:00:09.880309][RIGCTRL:debug] rig.c(1954):rig_set_freq return(0)
[2021-09-29 15:10:01.068478][00:00:09.880334][RIGCTRL:debug] ft1000mp.c(1665):ft1000mp_set_split_vfo return(0)
[2021-09-29 15:10:01.068478][00:00:09.880360][RIGCTRL:debug] rig.c(4629):rig_set_split_vfo return(0)
[2021-09-29 15:10:01.068478][00:00:09.880385][RIGCTRL:debug] ft1000mp.c(729):ft1000mp_set_freq entered
[2021-09-29 15:10:01.068478][00:00:09.880418][RIGCTRL:trace] ft1000mp_set_freq: requested freq = 3576000 Hz
[2021-09-29 15:10:01.068478][00:00:09.880442][RIGCTRL:debug] to_bcd called
[2021-09-29 15:10:01.068478][00:00:09.880470][RIGCTRL:debug] from_bcd called
[2021-09-29 15:10:01.068478][00:00:09.880495][RIGCTRL:debug] from_bcd called
[2021-09-29 15:10:01.068478][00:00:09.880513][RIGCTRL:trace] ft1000mp_set_freq: freq = 3576000 Hz
[2021-09-29 15:10:01.104588][00:00:09.915932][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:01.104588][00:00:09.915978][RIGCTRL:trace] 0000 00 76 35 00 8a .v5..
[2021-09-29 15:10:01.104588][00:00:09.916007][RIGCTRL:debug] ft1000mp.c(779):ft1000mp_set_freq return(0)
[2021-09-29 15:10:01.104588][00:00:09.916034][RIGCTRL:debug] ft1000mp.c(1712):ft1000mp_set_split_freq return(0)
[2021-09-29 15:10:01.104588][00:00:09.916063][RIGCTRL:debug] rig.c(3825):rig_set_split_freq return(0)
[2021-09-29 15:10:01.104588][00:00:09.916088][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4436) trace
[2021-09-29 15:10:01.104588][00:00:09.916113][RIGCTRL:debug] rig.c(4099):rig_set_split_mode entered
[2021-09-29 15:10:01.104588][00:00:09.916137][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4148) trace
[2021-09-29 15:10:01.104588][00:00:09.916162][RIGCTRL:debug] ft1000mp.c(1087):ft1000mp_set_vfo entered
[2021-09-29 15:10:01.104588][00:00:09.916185][RIGCTRL:trace] ft1000mp_set_vfo: called VFOB
[2021-09-29 15:10:01.104588][00:00:09.916211][RIGCTRL:debug] ft1000mp.c(1137):ft1000mp_set_vfo return(0)
[2021-09-29 15:10:01.104588][00:00:09.916236][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4175) trace
[2021-09-29 15:10:01.104588][00:00:09.916259][RIGCTRL:debug] ft1000mp_set_mode: called
[2021-09-29 15:10:01.104588][00:00:09.916283][RIGCTRL:trace] ft1000mp_set_mode: generic mode = USB
[2021-09-29 15:10:01.104588][00:00:09.916309][RIGCTRL:trace] ft1000mp_set_mode: current_vfo=VFOB
[2021-09-29 15:10:01.104588][00:00:09.916334][RIGCTRL:debug] ft1000mp.c(1608):ft1000mp_send_priv_cmd entered
[2021-09-29 15:10:01.104588][00:00:09.916358][RIGCTRL:trace] rig_flush: called for serial device
[2021-09-29 15:10:01.104588][00:00:09.916383][RIGCTRL:debug] serial.c(642):serial_flush entered
[2021-09-29 15:10:01.104588][00:00:09.916404][RIGCTRL:debug] tcflush
[2021-09-29 15:10:01.104588][00:00:09.916478][RIGCTRL:debug] serial.c(674):serial_flush return(0)
[2021-09-29 15:10:01.140738][00:00:09.952027][RIGCTRL:trace] write_block(): TX 5 bytes
[2021-09-29 15:10:01.140738][00:00:09.952094][RIGCTRL:trace] 0000 00 00 00 81 0c .....
[2021-09-29 15:10:01.140738][00:00:09.952152][RIGCTRL:debug] ft1000mp.c(1626):ft1000mp_send_priv_cmd return(0)
[2021-09-29 15:10:01.140738][00:00:09.952199][RIGCTRL:trace] ft1000mp_set_mode: cmd_index = 27
[2021-09-29 15:10:01.140738][00:00:09.952257][RIGCTRL:debug] ft1000mp.c(977):ft1000mp_set_mode return(0)
[2021-09-29 15:10:01.140738][00:00:09.952303][RIGCTRL:trace] C:/Users/bill/src/hamlib/src/rig.c(4183) trace
[2021-09-29 15:10:01.140738][00:00:09.952350][RIGCTRL:debug] ft1000mp.c(1087):ft1000mp_set_vfo entered
[2021-09-29 15:10:01.140738][00:00:09.952396][RIGCTRL:trace] ft1000mp_set_vfo: called VFOA
[2021-09-29 15:10:01.140738][00:00:09.952444][RIGCTRL:debug] ft1000mp.c(1137):ft1000mp_set_vfo return(0)
[2021-09-29 15:10:01.140738][00:00:09.952492][RIGCTRL:debug] rig.c(4197):rig_set_split_mode return(0)
[2021-09-29 15:10:01.140738][00:00:09.952541][RIGCTRL:debug] rig.c(4440):rig_set_split_freq_mode return(0)
73
Bill
G4WJS.
The text was updated successfully, but these errors were encountered: