FT-857D Swaps Frequencies on frequency update

The logs point to hamlib's FT-857 backend (ft857_init, ft857_get_vfo) and the rigctld command path, where VFO A's frequency update is being written to VFO B. Reproducing it needs an FT-857D connected over CAT, and the first step is to confirm which set-frequency command gpredict sends and how the backend handles it. Done would mean VFO A keeps its frequency during updates, verified on hardware. The issue has been inactive for over 1,300 days with no linked fix, so check whether the bug still exists in current hamlib first.

I'm using a OSX 11.6, hamlib 4.4, and gpredict 2.2.1. I installed gpredict via macports then again via homebrew. I also tried hamlib 4.0. All the varieties have the same problem.

When controlling my 857D via CAT with gpredict, VFOa will try and update frequency but will instead take VFOb's frequency and usually, VFOb will double in frequency. The problem only happens when VFOa tries to update frequency but it happens every time it tries to update. Below are some snippets from hamlib's (rigctld) verbose output.

Startup:

2022-07-06:21:29:43.568495: rigctld.c(579) Startup: rigctld -m 1022 -r /dev/tty.usbserial-AB0MVY9D -s 9600 -vvvvv -Z
2022-07-06:21:29:43.568549: rigctld Hamlib 4.4 Thu Dec 02 23:46:51 2021 +0000 SHA=5f8c4c
2022-07-06:21:29:43.568556: Report bugs to <[email protected]>

2022-07-06:21:29:43.568576: rig_check_rig_caps: p1=0x110933180, p2=0x110939700, rig_model=0x110933180, macro_name=0x110939700
2022-07-06:21:29:43.568585: rig.c(359):rig_check_rig_caps return(0)
2022-07-06:21:29:43.568948: yaesu: initrigs4_yaesu called
2022-07-06:21:29:43.568959: rig_register called
2022-07-06:21:29:43.568966: rig_register: rig_register (1021)
2022-07-06:21:29:43.568985: register.c(225):rig_register return(0)
2022-07-06:21:29:43.568992: rig_register called
2022-07-06:21:29:43.568998: rig_register: rig_register (1027)
2022-07-06:21:29:43.569005: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569012: rig_register called
2022-07-06:21:29:43.569019: rig_register: rig_register (1010)
2022-07-06:21:29:43.569025: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569048: rig_register called
2022-07-06:21:29:43.569054: rig_register: rig_register (1005)
2022-07-06:21:29:43.569060: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569067: rig_register called
2022-07-06:21:29:43.569072: rig_register: rig_register (1006)
2022-07-06:21:29:43.569078: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569084: rig_register called
2022-07-06:21:29:43.569090: rig_register: rig_register (1007)
2022-07-06:21:29:43.569095: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569103: rig_register called
2022-07-06:21:29:43.569109: rig_register: rig_register (1039)
2022-07-06:21:29:43.569115: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569120: rig_register called
2022-07-06:21:29:43.569126: rig_register: rig_register (1009)
2022-07-06:21:29:43.569132: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569137: rig_register called
2022-07-06:21:29:43.569143: rig_register: rig_register (1020)
2022-07-06:21:29:43.569149: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569155: rig_register called
2022-07-06:21:29:43.569161: rig_register: rig_register (1001)
2022-07-06:21:29:43.569167: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569173: rig_register called
2022-07-06:21:29:43.569178: rig_register: rig_register (1022)
2022-07-06:21:29:43.569185: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569190: rig_register called
2022-07-06:21:29:43.569196: rig_register: rig_register (1023)
2022-07-06:21:29:43.569202: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569208: rig_register called
2022-07-06:21:29:43.569213: rig_register: rig_register (1011)
2022-07-06:21:29:43.569219: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569225: rig_register called
2022-07-06:21:29:43.569231: rig_register: rig_register (1015)
2022-07-06:21:29:43.569237: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569242: rig_register called
2022-07-06:21:29:43.569248: rig_register: rig_register (1013)
2022-07-06:21:29:43.569254: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569260: rig_register called
2022-07-06:21:29:43.569266: rig_register: rig_register (1014)
2022-07-06:21:29:43.569271: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569277: rig_register called
2022-07-06:21:29:43.569283: rig_register: rig_register (1028)
2022-07-06:21:29:43.569289: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569295: rig_register called
2022-07-06:21:29:43.569301: rig_register: rig_register (1031)
2022-07-06:21:29:43.569307: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569312: rig_register called
2022-07-06:21:29:43.569318: rig_register: rig_register (1016)
2022-07-06:21:29:43.569324: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569330: rig_register called
2022-07-06:21:29:43.569335: rig_register: rig_register (1003)
2022-07-06:21:29:43.569341: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569347: rig_register called
2022-07-06:21:29:43.569353: rig_register: rig_register (1024)
2022-07-06:21:29:43.569359: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569365: rig_register called
2022-07-06:21:29:43.569370: rig_register: rig_register (1004)
2022-07-06:21:29:43.569376: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569382: rig_register called
2022-07-06:21:29:43.569388: rig_register: rig_register (1025)
2022-07-06:21:29:43.569394: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569400: rig_register called
2022-07-06:21:29:43.569406: rig_register: rig_register (1029)
2022-07-06:21:29:43.569411: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569417: rig_register called
2022-07-06:21:29:43.569423: rig_register: rig_register (1037)
2022-07-06:21:29:43.569429: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569435: rig_register called
2022-07-06:21:29:43.569440: rig_register: rig_register (1032)
2022-07-06:21:29:43.569447: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569452: rig_register called
2022-07-06:21:29:43.569458: rig_register: rig_register (1030)
2022-07-06:21:29:43.569464: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569470: rig_register called
2022-07-06:21:29:43.569476: rig_register: rig_register (1017)
2022-07-06:21:29:43.569481: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569487: rig_register called
2022-07-06:21:29:43.569493: rig_register: rig_register (1019)
2022-07-06:21:29:43.569499: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569504: rig_register called
2022-07-06:21:29:43.569510: rig_register: rig_register (1018)
2022-07-06:21:29:43.569516: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569522: rig_register called
2022-07-06:21:29:43.569527: rig_register: rig_register (1026)
2022-07-06:21:29:43.569533: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569539: rig_register called
2022-07-06:21:29:43.569545: rig_register: rig_register (1033)
2022-07-06:21:29:43.569551: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569556: rig_register called
2022-07-06:21:29:43.569562: rig_register: rig_register (1034)
2022-07-06:21:29:43.569568: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569573: rig_register called
2022-07-06:21:29:43.569579: rig_register: rig_register (1035)
2022-07-06:21:29:43.569585: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569591: rig_register called
2022-07-06:21:29:43.569597: rig_register: rig_register (1036)
2022-07-06:21:29:43.569602: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569608: rig_register called
2022-07-06:21:29:43.569614: rig_register: rig_register (1038)
2022-07-06:21:29:43.569619: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569625: rig_register called
2022-07-06:21:29:43.569631: rig_register: rig_register (1040)
2022-07-06:21:29:43.569636: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569642: rig_register called
2022-07-06:21:29:43.569648: rig_register: rig_register (1041)
2022-07-06:21:29:43.569653: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569659: rig_register called
2022-07-06:21:29:43.569665: rig_register: rig_register (1042)
2022-07-06:21:29:43.569670: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569676: rig_register called
2022-07-06:21:29:43.569682: rig_register: rig_register (1043)
2022-07-06:21:29:43.569687: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569694: rig_register called
2022-07-06:21:29:43.569699: rig_register: rig_register (1044)
2022-07-06:21:29:43.569705: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569711: rig_register called
2022-07-06:21:29:43.569716: rig_register: rig_register (1045)
2022-07-06:21:29:43.569722: register.c(225):rig_register return(0)
2022-07-06:21:29:43.569757: rig_init: rig has VFO_A
2022-07-06:21:29:43.569763: rig_init: rig has VFO_B
2022-07-06:21:29:43.569782: ft857_init: called 
2022-07-06:21:29:43.569793: rigctl_parse.c(1940):set_conf entered
2022-07-06:21:29:43.569799: rigctl_parse.c(1974):set_conf return(0)
2022-07-06:21:29:43.569805: main: twiddle=0, uplink=0, twiddle_rit=0
2022-07-06:21:29:43.569813: rig.c(688):rig_open entered
2022-07-06:21:29:43.569833: iofunc.c(68):port_open entered
2022-07-06:21:29:43.569844: serial.c(144):serial_open entered
2022-07-06:21:29:43.569854: serial_open: /dev/tty.usbserial-AB0MVY9D
2022-07-06:21:29:43.575083: serial.c(291):serial_setup entered
2022-07-06:21:29:43.575100: serial_setup: tcgetattr
2022-07-06:21:29:43.575122: serial_setup: cfmakeraw
2022-07-06:21:29:43.575133: serial_setup: cfsetispeed=9600,0x2580
2022-07-06:21:29:43.575144: serial_setup: cfsetospeed=9600,0x2580
2022-07-06:21:29:43.575153: serial_setup: data_bits=8
2022-07-06:21:29:43.575159: serial_setup: parity=0
2022-07-06:21:29:43.575487: serial_setup: tcsetattr TCSANOW
2022-07-06:21:29:43.576575: serial.c(631):serial_setup return(0)
2022-07-06:21:29:43.576611: serial.c(645):serial_flush entered
2022-07-06:21:29:43.576619: read_string called, rxmax=4095
2022-07-06:21:29:43.577964: serial.c(713):serial_flush return(0)
2022-07-06:21:29:43.628605: serial.c(266):serial_open return(0)
2022-07-06:21:29:43.628688: iofunc.c(186):port_open return(0)
2022-07-06:21:29:43.628795: rig.c(224):add_opened_rig return(0)
2022-07-06:21:29:43.628840: ft857_open: called
2022-07-06:21:29:43.628865: rig.c(1038) trace
2022-07-06:21:29:43.628899: rig.c(2903):rig_get_vfo entered
2022-07-06:21:29:43.628964: rig_get_vfo: cache check age=1000000ms
2022-07-06:21:29:43.629024: rig_get_vfo: cache miss age=1000000ms
2022-07-06:21:29:43.629046: rig.c(2941) trace
2022-07-06:21:29:43.629086: ft857_get_vfo: called 
2022-07-06:21:29:43.629115: ft857_read_eeprom: called 
2022-07-06:21:29:43.629283: write_block(): TX 5 bytes
2022-07-06:21:29:43.629366: 0000    00 68 00 00 bb                                      .h...           
2022-07-06:21:29:43.629387: read_block called
2022-07-06:21:29:43.639675: read_block(): RX 2 bytes
2022-07-06:21:29:43.639725: 0000    81 80                                               ..              
2022-07-06:21:29:43.639760: rig_get_vfo: elapsed=11ms
2022-07-06:21:29:43.639781: rig.c(2962):rig_get_vfo return(0)
2022-07-06:21:29:43.639802: rig.c(1112):rig_open return(0)
Opened rig model 1022, 'FT-857'
2022-07-06:21:29:43.639906: Backend version: 20210330.0, Status: Stable
2022-07-06:21:29:43.640759: main: Using IPV6
2022-07-06:21:29:43.640812: network.c(549):network_multicast_server under development
2022-07-06:21:29:43.640831: network.c(551):ADDR=0.0.0.0, port=4532
2022-07-06:21:29:43.640850: network.c(556): not starting multicast

Here is a once-per-second update where VFOa is not changed and everything stays okay:

2022-07-06:21:31:05.852479: rigctl_parse: cmd=t(74)
2022-07-06:21:31:05.852565: mutex_rigctld: client lock engaged
2022-07-06:21:31:05.852570: rigctl(d): t 'currVFO' '' '' ''
2022-07-06:21:31:05.852575: rigctl_parse: vfo_opt=0
2022-07-06:21:31:05.852580: rigctl_parse.c(2466):rigctl_get_ptt entered
2022-07-06:21:31:05.852586: rig.c(3280):rig_get_ptt entered
2022-07-06:21:31:05.852591: rig_get_ptt: cache check age=10885ms
2022-07-06:21:31:05.852595: rig_get_ptt: cache miss age=10885ms
2022-07-06:21:31:05.852600: rig.c(3324) trace
2022-07-06:21:31:05.852604: ft857_get_ptt: called 
2022-07-06:21:31:05.852609: ft857: cache timed out (998 ms)
2022-07-06:21:31:05.852613: ft857_get_status: called 
2022-07-06:21:31:05.852633: rig_flush: called for serial device
2022-07-06:21:31:05.852649: serial.c(645):serial_flush entered
2022-07-06:21:31:05.852655: read_string called, rxmax=4095
2022-07-06:21:31:05.853763: serial.c(713):serial_flush return(0)
2022-07-06:21:31:05.853798: write_block(): TX 5 bytes
2022-07-06:21:31:05.853805: 0000    00 00 00 00 f7                                      .....           
2022-07-06:21:31:05.853810: read_block called
2022-07-06:21:31:05.862866: read_block(): RX 1 bytes
2022-07-06:21:31:05.862884: 0000    ff                                                  .               
2022-07-06:21:31:05.862892: rig_get_ptt: elapsed=10ms
2022-07-06:21:31:05.862898: rig.c(3334):rig_get_ptt return(0)
2022-07-06:21:31:05.862908: rigctl_parse.c(2483):rigctl_get_ptt return(0)
2022-07-06:21:31:05.862973: mutex_rigctld: client lock disengaged
2022-07-06:21:31:05.862990: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:31:05.862999: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:31:05.863007: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:31:05.863015: rigctl_parse: called, interactive=1
2022-07-06:21:31:05.863106: rigctl_parse: cmd=f(66)
2022-07-06:21:31:05.863118: mutex_rigctld: client lock engaged
2022-07-06:21:31:05.863126: rigctl(d): f 'currVFO' '' '' ''
2022-07-06:21:31:05.863133: rigctl_parse: vfo_opt=0
2022-07-06:21:31:05.863141: rigctl_parse.c(2016):rigctl_get_freq entered
2022-07-06:21:31:05.863149: rig_get_freq(2114) called vfo=currVFO
2022-07-06:21:31:05.863164: vfo_fixup:(from rig_get_freq:2120) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:31:05.863173: vfo_fixup: Leaving currVFO alone
2022-07-06:21:31:05.863180: rig.c(2123) vfo=currVFO, curr_vfo=VFOB
2022-07-06:21:31:05.863200: rig_get_freq: cache miss age=863ms, cached_vfo=VFOB, asked_vfo=VFOB
2022-07-06:21:31:05.863209: rig_get_freq(2203): vfo_opt=0, model=1022
2022-07-06:21:31:05.863216: ft857_get_freq: called 
2022-07-06:21:31:05.863225: ft857: cache timed out (864 ms)
2022-07-06:21:31:05.863232: ft857_get_status: called 
2022-07-06:21:31:05.863239: rig_flush: called for serial device
2022-07-06:21:31:05.863247: serial.c(645):serial_flush entered
2022-07-06:21:31:05.863255: read_string called, rxmax=4095
2022-07-06:21:31:05.864369: serial.c(713):serial_flush return(0)
2022-07-06:21:31:05.864386: write_block(): TX 5 bytes
2022-07-06:21:31:05.864392: 0000    00 00 00 00 03                                      .....           
2022-07-06:21:31:05.864423: read_block called
2022-07-06:21:31:05.878806: read_block(): RX 5 bytes
2022-07-06:21:31:05.878819: 0000    02 94 49 46 01                                      ..IF.           
2022-07-06:21:31:05.878825: ft857_read_eeprom: called 
2022-07-06:21:31:05.878832: write_block(): TX 5 bytes
2022-07-06:21:31:05.878837: 0000    00 78 00 00 bb                                      .x...           
2022-07-06:21:31:05.878842: read_block called
2022-07-06:21:31:05.894872: read_block(): RX 2 bytes
2022-07-06:21:31:05.894887: 0000    a3 03                                               ..              
2022-07-06:21:31:05.894892: from_bcd_be called
2022-07-06:21:31:05.894904: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:05.894915: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:05.894922: rig_get_freq: elapsed=32ms
2022-07-06:21:31:05.894927: rig.c(2297):rig_get_freq return(0)
2022-07-06:21:31:05.894934: rigctl_parse.c(2042):rigctl_get_freq return(0)
2022-07-06:21:31:05.894968: mutex_rigctld: client lock disengaged
2022-07-06:21:31:05.894974: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:31:05.894982: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:31:05.894989: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:31:05.894995: rigctl_parse: called, interactive=1
2022-07-06:21:31:05.895095: rigctl_parse: cmd=F(46)
2022-07-06:21:31:05.895108: mutex_rigctld: client lock engaged
2022-07-06:21:31:05.895115: rigctl(d): F 'currVFO' '29449464' '' ''
2022-07-06:21:31:05.895121: rigctl_parse: vfo_opt=0
2022-07-06:21:31:05.895127: rigctl_parse.c(1992):rigctl_set_freq entered
2022-07-06:21:31:05.895136: rig_set_freq called vfo=currVFO, freq=29449464
2022-07-06:21:31:05.895143: vfo_fixup:(from rig_set_freq:1921) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:31:05.895150: vfo_fixup: Leaving currVFO alone
2022-07-06:21:31:05.895156: rig_set_freq: TARGETABLE_FREQ vfo=currVFO
2022-07-06:21:31:05.895162: rig.c(1949) trace
2022-07-06:21:31:05.895168: ft857_set_freq: called 
2022-07-06:21:31:05.895188: ft857: requested freq = 29449464 Hz
2022-07-06:21:31:05.895203: to_bcd_be called
2022-07-06:21:31:05.895208: rig_force_cache_timeout called
2022-07-06:21:31:05.895212: ft857_send_icmd: called 
2022-07-06:21:31:05.895236: write_block(): TX 5 bytes
2022-07-06:21:31:05.895248: 0000    02 94 49 46 01                                      ..IF.           
2022-07-06:21:31:05.895255: ft817_read_ack: called
2022-07-06:21:31:05.895261: read_block called
2022-07-06:21:31:05.974749: read_block(): RX 1 bytes
2022-07-06:21:31:05.974762: 0000    00                                                  .               
2022-07-06:21:31:05.974767: ft817_read_ack: ack received (0)
2022-07-06:21:31:05.974774: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:05.974781: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:05.974786: rig.c(2045) trace
2022-07-06:21:31:05.974790: rig_get_freq(2114) called vfo=currVFO
2022-07-06:21:31:05.974799: vfo_fixup:(from rig_get_freq:2120) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:31:05.974804: vfo_fixup: Leaving currVFO alone
2022-07-06:21:31:05.974809: rig.c(2123) vfo=currVFO, curr_vfo=VFOB
2022-07-06:21:31:05.974821: rig_get_freq: cache miss age=10000ms, cached_vfo=VFOB, asked_vfo=VFOB
2022-07-06:21:31:05.974826: rig_get_freq(2203): vfo_opt=0, model=1022
2022-07-06:21:31:05.974831: ft857_get_freq: called 
2022-07-06:21:31:05.974835: check_cache_timeout: cache invalid
2022-07-06:21:31:05.974840: ft857_get_status: called 
2022-07-06:21:31:05.974844: rig_flush: called for serial device
2022-07-06:21:31:05.974849: serial.c(645):serial_flush entered
2022-07-06:21:31:05.974854: read_string called, rxmax=4095
2022-07-06:21:31:05.976211: serial.c(713):serial_flush return(0)
2022-07-06:21:31:05.976231: write_block(): TX 5 bytes
2022-07-06:21:31:05.976237: 0000    00 00 00 00 03                                      .....           
2022-07-06:21:31:05.976244: read_block called
2022-07-06:21:31:05.990751: read_block(): RX 5 bytes
2022-07-06:21:31:05.990766: 0000    02 94 49 46 01                                      ..IF.           
2022-07-06:21:31:05.990772: ft857_read_eeprom: called 
2022-07-06:21:31:05.990793: write_block(): TX 5 bytes
2022-07-06:21:31:05.990805: 0000    00 78 00 00 bb                                      .x...           
2022-07-06:21:31:05.990812: read_block called
2022-07-06:21:31:06.006875: read_block(): RX 2 bytes
2022-07-06:21:31:06.006919: 0000    a3 03                                               ..              
2022-07-06:21:31:06.006925: from_bcd_be called
2022-07-06:21:31:06.006969: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:06.006982: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:06.007057: rig_get_freq: elapsed=32ms
2022-07-06:21:31:06.007077: rig.c(2297):rig_get_freq return(0)
2022-07-06:21:31:06.007089: rig_set_freq: Asked freq=29449464, got freq=29449460
2022-07-06:21:31:06.007109: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:31:06.007122: rig_set_freq: elapsed=112ms
2022-07-06:21:31:06.007131: rig.c(2072):rig_set_freq return(0)
2022-07-06:21:31:06.007146: rigctl_parse.c(2004):rigctl_set_freq return(0)
2022-07-06:21:31:06.007154: rigctl_parse: return#2 RPRT 0
2022-07-06:21:31:06.007199: mutex_rigctld: client lock disengaged
2022-07-06:21:31:06.007214: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:31:06.007225: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:31:06.007235: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:31:06.007312: rigctl_parse: called, interactive=1
2022-07-06:21:31:06.012896: rigctl_parse: cmd=f(66)
2022-07-06:21:31:06.012942: mutex_rigctld: client lock engaged
2022-07-06:21:31:06.012947: rigctl(d): f 'currVFO' '' '' ''
2022-07-06:21:31:06.012952: rigctl_parse: vfo_opt=0
2022-07-06:21:31:06.012956: rigctl_parse.c(2016):rigctl_get_freq entered
2022-07-06:21:31:06.012962: rig_get_freq(2114) called vfo=currVFO
2022-07-06:21:31:06.012972: vfo_fixup:(from rig_get_freq:2120) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:31:06.012977: vfo_fixup: Leaving currVFO alone
2022-07-06:21:31:06.012981: rig.c(2123) vfo=currVFO, curr_vfo=VFOB
2022-07-06:21:31:06.012995: rig_get_freq: VFOB cache hit age=5ms, freq=29449460
2022-07-06:21:31:06.013000: rig_get_freq: elapsed=0ms
2022-07-06:21:31:06.013005: rig.c(2185):rig_get_freq return(0)
2022-07-06:21:31:06.013010: rigctl_parse.c(2042):rigctl_get_freq return(0)
2022-07-06:21:31:06.013026: mutex_rigctld: client lock disengaged
2022-07-06:21:31:06.013030: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:31:06.013035: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:31:06.013040: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:31:06.013044: rigctl_parse: called, interactive=1

And when VFOa changes frequency. All of the output is the same as a once-per-second update but then continues on after that. Change starts at timestamp 2022-07-06:21:33:25.024694:

2022-07-06:21:33:24.862924: rigctl_parse: cmd=t(74)
2022-07-06:21:33:24.862941: mutex_rigctld: client lock engaged
2022-07-06:21:33:24.862946: rigctl(d): t 'currVFO' '' '' ''
2022-07-06:21:33:24.862979: rigctl_parse: vfo_opt=0
2022-07-06:21:33:24.862984: rigctl_parse.c(2466):rigctl_get_ptt entered
2022-07-06:21:33:24.862989: rig.c(3280):rig_get_ptt entered
2022-07-06:21:33:24.862994: rig_get_ptt: cache check age=10902ms
2022-07-06:21:33:24.863029: rig_get_ptt: cache miss age=10902ms
2022-07-06:21:33:24.863034: rig.c(3324) trace
2022-07-06:21:33:24.863038: ft857_get_ptt: called 
2022-07-06:21:33:24.863043: ft857: cache timed out (1013 ms)
2022-07-06:21:33:24.863048: ft857_get_status: called 
2022-07-06:21:33:24.863052: rig_flush: called for serial device
2022-07-06:21:33:24.863058: serial.c(645):serial_flush entered
2022-07-06:21:33:24.863087: read_string called, rxmax=4095
2022-07-06:21:33:24.864412: serial.c(713):serial_flush return(0)
2022-07-06:21:33:24.864471: write_block(): TX 5 bytes
2022-07-06:21:33:24.864477: 0000    00 00 00 00 f7                                      .....           
2022-07-06:21:33:24.864482: read_block called
2022-07-06:21:33:24.872837: read_block(): RX 1 bytes
2022-07-06:21:33:24.872850: 0000    ff                                                  .               
2022-07-06:21:33:24.872890: rig_get_ptt: elapsed=10ms
2022-07-06:21:33:24.872929: rig.c(3334):rig_get_ptt return(0)
2022-07-06:21:33:24.872936: rigctl_parse.c(2483):rigctl_get_ptt return(0)
2022-07-06:21:33:24.872959: mutex_rigctld: client lock disengaged
2022-07-06:21:33:24.872965: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:33:24.872971: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:33:24.872978: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:33:24.872984: rigctl_parse: called, interactive=1
2022-07-06:21:33:24.873066: rigctl_parse: cmd=f(66)
2022-07-06:21:33:24.873078: mutex_rigctld: client lock engaged
2022-07-06:21:33:24.873085: rigctl(d): f 'currVFO' '' '' ''
2022-07-06:21:33:24.873092: rigctl_parse: vfo_opt=0
2022-07-06:21:33:24.873099: rigctl_parse.c(2016):rigctl_get_freq entered
2022-07-06:21:33:24.873105: rig_get_freq(2114) called vfo=currVFO
2022-07-06:21:33:24.873115: vfo_fixup:(from rig_get_freq:2120) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:33:24.873120: vfo_fixup: Leaving currVFO alone
2022-07-06:21:33:24.873125: rig.c(2123) vfo=currVFO, curr_vfo=VFOB
2022-07-06:21:33:24.873137: rig_get_freq: cache miss age=879ms, cached_vfo=VFOB, asked_vfo=VFOB
2022-07-06:21:33:24.873142: rig_get_freq(2203): vfo_opt=0, model=1022
2022-07-06:21:33:24.873147: ft857_get_freq: called 
2022-07-06:21:33:24.873152: ft857: cache timed out (881 ms)
2022-07-06:21:33:24.873156: ft857_get_status: called 
2022-07-06:21:33:24.873161: rig_flush: called for serial device
2022-07-06:21:33:24.873166: serial.c(645):serial_flush entered
2022-07-06:21:33:24.873297: read_string called, rxmax=4095
2022-07-06:21:33:24.874626: serial.c(713):serial_flush return(0)
2022-07-06:21:33:24.874672: write_block(): TX 5 bytes
2022-07-06:21:33:24.874678: 0000    00 00 00 00 03                                      .....           
2022-07-06:21:33:24.874683: read_block called
2022-07-06:21:33:24.888899: read_block(): RX 5 bytes
2022-07-06:21:33:24.888927: 0000    02 94 49 46 01                                      ..IF.           
2022-07-06:21:33:24.888939: ft857_read_eeprom: called 
2022-07-06:21:33:24.888955: write_block(): TX 5 bytes
2022-07-06:21:33:24.888966: 0000    00 78 00 00 bb                                      .x...           
2022-07-06:21:33:24.888976: read_block called
2022-07-06:21:33:24.904700: read_block(): RX 2 bytes
2022-07-06:21:33:24.904740: 0000    a3 03                                               ..              
2022-07-06:21:33:24.904755: from_bcd_be called
2022-07-06:21:33:24.904786: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:24.904816: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:24.904836: rig_get_freq: elapsed=32ms
2022-07-06:21:33:24.904849: rig.c(2297):rig_get_freq return(0)
2022-07-06:21:33:24.904865: rigctl_parse.c(2042):rigctl_get_freq return(0)
2022-07-06:21:33:24.904927: mutex_rigctld: client lock disengaged
2022-07-06:21:33:24.904942: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:33:24.904956: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:33:24.904969: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:33:24.904982: rigctl_parse: called, interactive=1
2022-07-06:21:33:24.905245: rigctl_parse: cmd=F(46)
2022-07-06:21:33:24.905327: mutex_rigctld: client lock engaged
2022-07-06:21:33:24.905349: rigctl(d): F 'currVFO' '29449462' '' ''
2022-07-06:21:33:24.905363: rigctl_parse: vfo_opt=0
2022-07-06:21:33:24.905379: rigctl_parse.c(1992):rigctl_set_freq entered
2022-07-06:21:33:24.905397: rig_set_freq called vfo=currVFO, freq=29449462
2022-07-06:21:33:24.905452: vfo_fixup:(from rig_set_freq:1921) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:33:24.905513: vfo_fixup: Leaving currVFO alone
2022-07-06:21:33:24.905531: rig_set_freq: TARGETABLE_FREQ vfo=currVFO
2022-07-06:21:33:24.905543: rig.c(1949) trace
2022-07-06:21:33:24.905556: ft857_set_freq: called 
2022-07-06:21:33:24.905569: ft857: requested freq = 29449462 Hz
2022-07-06:21:33:24.905583: to_bcd_be called
2022-07-06:21:33:24.905594: rig_force_cache_timeout called
2022-07-06:21:33:24.905606: ft857_send_icmd: called 
2022-07-06:21:33:24.905651: write_block(): TX 5 bytes
2022-07-06:21:33:24.905675: 0000    02 94 49 46 01                                      ..IF.           
2022-07-06:21:33:24.905693: ft817_read_ack: called
2022-07-06:21:33:24.905710: read_block called
2022-07-06:21:33:24.984962: read_block(): RX 1 bytes
2022-07-06:21:33:24.985015: 0000    00                                                  .               
2022-07-06:21:33:24.985037: ft817_read_ack: ack received (0)
2022-07-06:21:33:24.985066: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:24.985095: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:24.985114: rig.c(2045) trace
2022-07-06:21:33:24.985134: rig_get_freq(2114) called vfo=currVFO
2022-07-06:21:33:24.985168: vfo_fixup:(from rig_get_freq:2120) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:33:24.985200: vfo_fixup: Leaving currVFO alone
2022-07-06:21:33:24.985219: rig.c(2123) vfo=currVFO, curr_vfo=VFOB
2022-07-06:21:33:24.985269: rig_get_freq: cache miss age=10000ms, cached_vfo=VFOB, asked_vfo=VFOB
2022-07-06:21:33:24.985289: rig_get_freq(2203): vfo_opt=0, model=1022
2022-07-06:21:33:24.985309: ft857_get_freq: called 
2022-07-06:21:33:24.985327: check_cache_timeout: cache invalid
2022-07-06:21:33:24.985345: ft857_get_status: called 
2022-07-06:21:33:24.985363: rig_flush: called for serial device
2022-07-06:21:33:24.985385: serial.c(645):serial_flush entered
2022-07-06:21:33:24.985405: read_string called, rxmax=4095
2022-07-06:21:33:24.986824: serial.c(713):serial_flush return(0)
2022-07-06:21:33:24.986904: write_block(): TX 5 bytes
2022-07-06:21:33:24.986929: 0000    00 00 00 00 03                                      .....           
2022-07-06:21:33:24.986948: read_block called
2022-07-06:21:33:25.000795: read_block(): RX 5 bytes
2022-07-06:21:33:25.000842: 0000    02 94 49 46 01                                      ..IF.           
2022-07-06:21:33:25.000862: ft857_read_eeprom: called 
2022-07-06:21:33:25.000891: write_block(): TX 5 bytes
2022-07-06:21:33:25.000911: 0000    00 78 00 00 bb                                      .x...           
2022-07-06:21:33:25.000930: read_block called
2022-07-06:21:33:25.017060: read_block(): RX 2 bytes
2022-07-06:21:33:25.017105: 0000    a3 03                                               ..              
2022-07-06:21:33:25.017126: from_bcd_be called
2022-07-06:21:33:25.017168: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.017214: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.017244: rig_get_freq: elapsed=32ms
2022-07-06:21:33:25.017266: rig.c(2297):rig_get_freq return(0)
2022-07-06:21:33:25.017286: rig_set_freq: Asked freq=29449462, got freq=29449460
2022-07-06:21:33:25.017320: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.017339: rig_set_freq: elapsed=112ms
2022-07-06:21:33:25.017372: rig.c(2072):rig_set_freq return(0)
2022-07-06:21:33:25.017403: rigctl_parse.c(2004):rigctl_set_freq return(0)
2022-07-06:21:33:25.017423: rigctl_parse: return#2 RPRT 0
2022-07-06:21:33:25.017536: mutex_rigctld: client lock disengaged
2022-07-06:21:33:25.017596: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:33:25.017658: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:33:25.017677: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:33:25.017693: rigctl_parse: called, interactive=1
2022-07-06:21:33:25.024206: rigctl_parse: cmd=f(66)
2022-07-06:21:33:25.024244: mutex_rigctld: client lock engaged
2022-07-06:21:33:25.024259: rigctl(d): f 'currVFO' '' '' ''
2022-07-06:21:33:25.024272: rigctl_parse: vfo_opt=0
2022-07-06:21:33:25.024286: rigctl_parse.c(2016):rigctl_get_freq entered
2022-07-06:21:33:25.024300: rig_get_freq(2114) called vfo=currVFO
2022-07-06:21:33:25.024327: vfo_fixup:(from rig_get_freq:2120) vfo=currVFO, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.024340: vfo_fixup: Leaving currVFO alone
2022-07-06:21:33:25.024354: rig.c(2123) vfo=currVFO, curr_vfo=VFOB
2022-07-06:21:33:25.024389: rig_get_freq: VFOB cache hit age=7ms, freq=29449460
2022-07-06:21:33:25.024407: rig_get_freq: elapsed=0ms
2022-07-06:21:33:25.024421: rig.c(2185):rig_get_freq return(0)
2022-07-06:21:33:25.024434: rigctl_parse.c(2042):rigctl_get_freq return(0)
2022-07-06:21:33:25.024464: mutex_rigctld: client lock disengaged
2022-07-06:21:33:25.024476: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:33:25.024489: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:33:25.024501: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:33:25.024514: rigctl_parse: called, interactive=1
2022-07-06:21:33:25.024694: rigctl_parse: cmd=t(74)
2022-07-06:21:33:25.024712: mutex_rigctld: client lock engaged
2022-07-06:21:33:25.024724: rigctl(d): t 'currVFO' '' '' ''
2022-07-06:21:33:25.024737: rigctl_parse: vfo_opt=0
2022-07-06:21:33:25.024749: rigctl_parse.c(2466):rigctl_get_ptt entered
2022-07-06:21:33:25.024761: rig.c(3280):rig_get_ptt entered
2022-07-06:21:33:25.024774: rig_get_ptt: cache check age=10039ms
2022-07-06:21:33:25.024788: rig_get_ptt: cache miss age=10039ms
2022-07-06:21:33:25.024800: rig.c(3324) trace
2022-07-06:21:33:25.024813: ft857_get_ptt: called 
2022-07-06:21:33:25.024825: ft857: cache timed out (152 ms)
2022-07-06:21:33:25.024837: ft857_get_status: called 
2022-07-06:21:33:25.024849: rig_flush: called for serial device
2022-07-06:21:33:25.024864: serial.c(645):serial_flush entered
2022-07-06:21:33:25.024876: read_string called, rxmax=4095
2022-07-06:21:33:25.026213: serial.c(713):serial_flush return(0)
2022-07-06:21:33:25.026249: write_block(): TX 5 bytes
2022-07-06:21:33:25.026262: 0000    00 00 00 00 f7                                      .....           
2022-07-06:21:33:25.026272: read_block called
2022-07-06:21:33:25.049152: read_block(): RX 1 bytes
2022-07-06:21:33:25.049205: 0000    ff                                                  .               
2022-07-06:21:33:25.049235: rig_get_ptt: elapsed=24ms
2022-07-06:21:33:25.049261: rig.c(3334):rig_get_ptt return(0)
2022-07-06:21:33:25.049292: rigctl_parse.c(2483):rigctl_get_ptt return(0)
2022-07-06:21:33:25.049392: mutex_rigctld: client lock disengaged
2022-07-06:21:33:25.049429: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:33:25.049462: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:33:25.049496: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:33:25.049527: rigctl_parse: called, interactive=1
2022-07-06:21:33:25.049808: rigctl_parse: cmd=I(49)
2022-07-06:21:33:25.049863: mutex_rigctld: client lock engaged
2022-07-06:21:33:25.049885: rigctl(d): I 'currVFO' '145902664' '' ''
2022-07-06:21:33:25.049905: rigctl_parse: vfo_opt=0
2022-07-06:21:33:25.049924: rigctl_parse.c(2753):rigctl_set_split_freq entered
2022-07-06:21:33:25.049961: rig_set_split_freq called vfo=TX, curr_vfo=VFOB
2022-07-06:21:33:25.049981: rig_get_freq(2114) called vfo=VFOB
2022-07-06:21:33:25.050024: vfo_fixup:(from rig_get_freq:2120) vfo=VFOB, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.050051: vfo_fixup: final vfo=VFOB
2022-07-06:21:33:25.050078: rig.c(2123) vfo=VFOB, curr_vfo=VFOB
2022-07-06:21:33:25.050328: rig_get_freq: VFOB cache hit age=32ms, freq=29449460
2022-07-06:21:33:25.050353: rig_get_freq: elapsed=0ms
2022-07-06:21:33:25.050373: rig.c(2185):rig_get_freq return(0)
2022-07-06:21:33:25.050395: vfo_fixup:(from rig_set_split_freq:4008) vfo=TX, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.050416: vfo_fixup(1825): split=0, vfo==TX tx_vfo=VFOB
2022-07-06:21:33:25.050437: vfo_fixup: RIG_VFO_TX changed to TX, split=0, satmode=0
2022-07-06:21:33:25.050456: rig.c(4021) trace
2022-07-06:21:33:25.050477: rig_set_freq called vfo=VFOB, freq=145902664
2022-07-06:21:33:25.050497: vfo_fixup:(from rig_set_freq:1921) vfo=VFOB, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.050516: vfo_fixup: final vfo=VFOB
2022-07-06:21:33:25.050535: rig_set_freq: TARGETABLE_FREQ vfo=VFOB
2022-07-06:21:33:25.050553: rig.c(1949) trace
2022-07-06:21:33:25.050571: ft857_set_freq: called 
2022-07-06:21:33:25.050589: ft857: requested freq = 145902664 Hz
2022-07-06:21:33:25.050608: to_bcd_be called
2022-07-06:21:33:25.050625: rig_force_cache_timeout called
2022-07-06:21:33:25.050642: ft857_send_icmd: called 
2022-07-06:21:33:25.050676: write_block(): TX 5 bytes
2022-07-06:21:33:25.050699: 0000    14 59 02 66 01                                      .Y.f.           
2022-07-06:21:33:25.050717: ft817_read_ack: called
2022-07-06:21:33:25.050985: read_block called
2022-07-06:21:33:25.112753: read_block(): RX 1 bytes
2022-07-06:21:33:25.112800: 0000    00                                                  .               
2022-07-06:21:33:25.112820: ft817_read_ack: ack received (0)
2022-07-06:21:33:25.112848: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.112876: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.112895: rig.c(2045) trace
2022-07-06:21:33:25.112918: rig_get_freq(2114) called vfo=VFOB
2022-07-06:21:33:25.112953: vfo_fixup:(from rig_get_freq:2120) vfo=VFOB, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.112973: vfo_fixup: final vfo=VFOB
2022-07-06:21:33:25.112991: rig.c(2123) vfo=VFOB, curr_vfo=VFOB
2022-07-06:21:33:25.113042: rig_get_freq: cache miss age=10000ms, cached_vfo=VFOB, asked_vfo=VFOB
2022-07-06:21:33:25.113063: rig_get_freq(2203): vfo_opt=0, model=1022
2022-07-06:21:33:25.113082: ft857_get_freq: called 
2022-07-06:21:33:25.113101: check_cache_timeout: cache invalid
2022-07-06:21:33:25.113119: ft857_get_status: called 
2022-07-06:21:33:25.113138: rig_flush: called for serial device
2022-07-06:21:33:25.113157: serial.c(645):serial_flush entered
2022-07-06:21:33:25.113177: read_string called, rxmax=4095
2022-07-06:21:33:25.114594: serial.c(713):serial_flush return(0)
2022-07-06:21:33:25.114679: write_block(): TX 5 bytes
2022-07-06:21:33:25.114705: 0000    00 00 00 00 03                                      .....           
2022-07-06:21:33:25.114729: read_block called
2022-07-06:21:33:25.128844: read_block(): RX 5 bytes
2022-07-06:21:33:25.128899: 0000    14 59 02 66 01                                      .Y.f.           
2022-07-06:21:33:25.128920: ft857_read_eeprom: called 
2022-07-06:21:33:25.128951: write_block(): TX 5 bytes
2022-07-06:21:33:25.128972: 0000    00 78 00 00 bb                                      .x...           
2022-07-06:21:33:25.128992: read_block called
2022-07-06:21:33:25.144831: read_block(): RX 2 bytes
2022-07-06:21:33:25.144876: 0000    a3 03                                               ..              
2022-07-06:21:33:25.144896: from_bcd_be called
2022-07-06:21:33:25.144938: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.144984: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.145014: rig_get_freq: elapsed=32ms
2022-07-06:21:33:25.145034: rig.c(2297):rig_get_freq return(0)
2022-07-06:21:33:25.145056: rig_set_freq: Asked freq=145902664, got freq=145902660
2022-07-06:21:33:25.145080: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.145099: rig.c(2067) trace
2022-07-06:21:33:25.145118: rig.c(2765):rig_set_vfo entered
2022-07-06:21:33:25.145137: rig_set_vfo called vfo=VFOB
2022-07-06:21:33:25.145154: rig_set_vfo ********************** called vfo=VFOB
2022-07-06:21:33:25.145178: vfo_fixup:(from rig_set_vfo:2784) vfo=VFOB, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.145199: vfo_fixup: final vfo=VFOB
2022-07-06:21:33:25.145217: vfo_fixup:(from rig_set_vfo:2807) vfo=VFOB, vfo_curr=VFOB, split=0
2022-07-06:21:33:25.145234: vfo_fixup: final vfo=VFOB
2022-07-06:21:33:25.145252: rig.c(2824) trace
2022-07-06:21:33:25.145270: ft857_get_vfo: called 
2022-07-06:21:33:25.145287: ft857_read_eeprom: called 
2022-07-06:21:33:25.145319: write_block(): TX 5 bytes
2022-07-06:21:33:25.145340: 0000    00 68 00 00 bb                                      .h...           
2022-07-06:21:33:25.145357: read_block called
2022-07-06:21:33:25.160466: read_block(): RX 2 bytes
2022-07-06:21:33:25.160487: 0000    81 80                                               ..              
2022-07-06:21:33:25.160497: ft857_set_vfo: called 
2022-07-06:21:33:25.160506: rig_set_vfo: rig->state.current_vfo=VFOB
2022-07-06:21:33:25.160515: rig.c(2850) trace
2022-07-06:21:33:25.160523: ft857_get_freq: called 
2022-07-06:21:33:25.160531: ft857: using cache (15 ms)
2022-07-06:21:33:25.160540: from_bcd_be called
2022-07-06:21:33:25.160551: rig_set_vfo: retcode from rig_get_freq = Command completed successfully
ft857: using cache (15 ms)
from_bcd_be called
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
ft857: using cache (15 ms)
from_bcd_be called
from_bcd_be called
2022-07-06:21:33:25.160564: rig.c(1590):set_cache_freq return(0)
2022-07-06:21:33:25.160573: rig_set_vfo: return 0, vfo=VFOB, curr_vfo=VFOB
2022-07-06:21:33:25.160585: rig_set_vfo: elapsed=15ms
2022-07-06:21:33:25.160594: rig.c(2878):rig_set_vfo return(0)
2022-07-06:21:33:25.160605: rig_set_freq: elapsed=110ms
2022-07-06:21:33:25.160614: rig.c(2072):rig_set_freq return(0)
2022-07-06:21:33:25.160624: rig_set_split_freq: elapsed=111ms
2022-07-06:21:33:25.160633: rig.c(4035):rig_set_split_freq return(0)
2022-07-06:21:33:25.160643: rigctl_parse.c(2757):rigctl_set_split_freq return(0)
2022-07-06:21:33:25.160652: rigctl_parse: return#2 RPRT 0
2022-07-06:21:33:25.160693: mutex_rigctld: client lock disengaged
2022-07-06:21:33:25.160702: rigctl_parse.c(1767):rigctl_parse return(0)
2022-07-06:21:33:25.160711: handle_socket: doing rigctl_parse vfo_mode=0
2022-07-06:21:33:25.160720: rigctl_parse.c(660):rigctl_parse entered
2022-07-06:21:33:25.160728: rigctl_parse: called, interactive=1