search

csete / gpredict

Gpredict satellite tracking application
http://gpredict.oz9aec.net/
GNU General Public License v2.0
880 stars 250 forks source link

FT-857D Swaps Frequencies on frequency update #294

Open joshuajayg opened 2 years ago

joshuajayg commented 2 years ago

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 <hamlib-developer@lists.sourceforge.net>

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
mdblack98 commented 2 years ago

Hopefully you're able to compile Hamlib? If so please use the latest and see if it still has a problem. git clone https://github.com/Hamlib/Hamlib.git

Mike W9MDB

On Wednesday, July 6, 2022 at 04:48:27 PM CDT, Joshua Griffin ***@***.***> wrote:

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 @.***

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:23.999615:

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

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you are subscribed to this thread.Message ID: @.***>

mdblack98 commented 2 years ago

Also...try and use the --vfo option with rigctld. Mike W9MDB

On Wednesday, July 6, 2022 at 04:48:27 PM CDT, Joshua Griffin ***@***.***> wrote:

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 @.***

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:23.999615:

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

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you are subscribed to this thread.Message ID: @.***>

joshuajayg commented 2 years ago

I can compile hamlib from source; that's how I got 4.0. I'll try with the latest version. I tried --vfo and -o. The radio does not respond and the output of rigctld stops after... 

2022-07-06:22:00:20.498801: rig_parse_vfo called
2022-07-06:22:00:20.498806: rig_parse_vfo: '1' not found so vfo='None'
mdblack98 commented 2 years ago

I need to see your command line.

On Wednesday, July 6, 2022 at 05:03:11 PM CDT, Joshua Griffin ***@***.***> wrote:

I can compile hamlib from source; that's how I got 4.0. I'll try with the latest version. I tried --vfo and -o. The radio does not respond and the output of rigctld stops after... 2022-07-06:22:00:20.498801: rig_parse_vfo called 2022-07-06:22:00:20.498806: rig_parse_vfo: '1' not found so vfo='None'

— Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you commented.Message ID: @.***>

joshuajayg commented 2 years ago

blah_blah@blahblah ~ % rigctld -m 1022 -r /dev/tty.usbserial-AB0MVY9D -s 9600 -vfo -vvvvv -Z and blah_blah@blahblah ~ % rigctld -m 1022 -r /dev/tty.usbserial-AB0MVY9D -s 9600 -o -vvvvv -Z

joshuajayg commented 2 years ago

Well, Mike, Hamlib 4.4 is the latest version which I have installed and it works fine with other programs but I'm unable to compile it myself. It doesn't come with config or Makefile.in and I'm having trouble with automake. Sooo... compliling myself isn't happening today.

mdblack98 commented 1 year ago

Try the tar file from here https://n0nb.users.sourceforge.net/

joshuajayg commented 1 year ago

Sorry, I don't have the radio anymore so I can't test this.