Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

IC-7100 VFOB split not changing VFOB #764

Closed
mdblack98 opened this issue Aug 14, 2021 · 3 comments
Closed

IC-7100 VFOB split not changing VFOB #764

mdblack98 opened this issue Aug 14, 2021 · 3 comments
Labels
bug critical A problem for common operations with WSJT-X, GPredict, RigPi, etc. needs test Patches have been submitted but need testing to close issue

Comments

@mdblack98
Copy link
Contributor

I can confirm that this seems to be a regression in 2.4.0. I've run my IC-7100 in split/rig mode for about a year now on v2.3.0 and it switches the VFOb settings correctly on transmit. Once I upgraded to 2.4.0, VFOb is stuck on 40 meters when I'm working 20 meters. I downgraded back to 2.3.0 and the issue resolved itself.

Settings->Radio->Mode is set to Data/Pkt.

73, TJ N7TJH
@mdblack98 mdblack98 added bug needs test Patches have been submitted but need testing to close issue labels Aug 14, 2021
@mdblack98 mdblack98 added this to the 4.3 milestone Aug 14, 2021
@mdblack98 mdblack98 reopened this Aug 28, 2021
@mdblack98
Copy link
Contributor Author

I did just notice a difference in how it acts compared to 2.3.0. When it first starts transmitting, it quickly flips back and forth between VFOA and VFOB before finally landing on VFOB and transmitting. Before, on 2.3.0, it would only switch from VFOA to VFOB once, transmit, then switch back to VFOA.
N7TJH

@mdblack98 mdblack98 added needs test Patches have been submitted but need testing to close issue and removed needs test Patches have been submitted but need testing to close issue labels Aug 29, 2021
@mdblack98
Copy link
Contributor Author

Bill Somerville g4wjs@classdesign.com
Unsubscribe
To:
hamlib-developer@lists.sourceforge.net

Sun, Aug 29 at 2:16 PM

Hi all,

master at commit a0672e4 has regressed, it no longer reliably sets the split Tx VFO frequency as requested by a set_split_freq_mode(). Note that in the first (failing) example below the SUB VFO is never selected even though the MAIN VFO is selected no less than six times while attempting to execute this command! The end result is that the requested SPLIT Tx VFO frequency is set on the MAIN Rx VFO.

Note also the second trace output below where in the same session set_split_freq_mode() does manage to set the right frequency in the right VFO, rather inconistent behaviour!
warning: [18:52:57.272337][RIGCTRL][trace] C:\Users\bill\src\k1jt\wsjtx\Transceiver\HamlibTransceiver.cpp(989) do_tx_frequency: rig_set_split_freq_mode freq=21074500 mode = USB
warning: [18:52:57.273089][RIGCTRL][debug] () : rig.c(4363):rig_set_split_freq_mode entered
warning: [18:52:57.273089][RIGCTRL][trace] () : vfo_fixup: vfo=TX, vfo_curr=Main
warning: [18:52:57.273837][RIGCTRL][trace] () : vfo_fixup: RIG_VFO_TX changed to Main, split=0, satmode=0
warning: [18:52:57.273837][RIGCTRL][debug] () : rig_set_split_freq_mode: vfo=Main, tx_freq=21074500, tx_mode=USB, tx_width=-1
warning: [18:52:57.376657][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(4385) trace
warning: [18:52:57.376657][RIGCTRL][debug] () : icom_set_split_freq_mode called vfo=Main
warning: [18:52:57.377407][RIGCTRL][debug] () : icom_set_split_freq_mode: curr_vfo=Main
warning: [18:52:57.377407][RIGCTRL][debug] () : icom_set_split_freq_mode: before get_split_vfos rx_vfo=Main tx_vfo=Main
warning: [18:52:57.378158][RIGCTRL][debug] () : icom_get_split_vfos called
warning: [18:52:57.378158][RIGCTRL][trace] () : icom_get_split_vfos: VFO_HAS_MAIN_SUB_ONLY, split=0, rx=Main, tx=Main
warning: [18:52:57.378158][RIGCTRL][debug] () : icom.c(4920):icom_get_split_vfos return(0)
warning: [18:52:57.378908][RIGCTRL][debug] () : icom_set_split_freq_mode: after get_split_vfos rx_vfo=Main tx_vfo=Main
warning: [18:52:57.378908][RIGCTRL][trace] () : icom_set_split_freq_mode: split not on so using requested vfo=Main
warning: [18:52:57.379659][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(5721) trace
warning: [18:52:57.379659][RIGCTRL][debug] () : rig.c(2616):rig_set_vfo entered
warning: [18:52:57.379659][RIGCTRL][debug] () : rig_set_vfo called vfo=Main
warning: [18:52:57.380409][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:57.380409][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:57.380409][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2675) trace
warning: [18:52:57.381160][RIGCTRL][debug] () : icom_set_vfo called vfo=Main
warning: [18:52:57.381160][RIGCTRL][trace] () : icom_set_vfo: line#2473
warning: [18:52:57.381160][RIGCTRL][trace] () : icom_set_vfo: line#2630
warning: [18:52:57.381910][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:57.381910][RIGCTRL][debug] () : icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
warning: [18:52:57.381910][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:57.382661][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:57.382661][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:57.382661][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:57.383411][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:57.383411][RIGCTRL][debug] () : tcflush
warning: [18:52:57.383411][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:57.384161][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:57.384161][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:57.384912][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.384912][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.417184][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:57.417934][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:57.417934][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:57.417934][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.418685][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.429192][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:57.429943][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:57.429943][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:57.429943][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:57.429943][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:57.430693][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:57.546271][RIGCTRL][trace] () : icom_set_vfo: line#2633
warning: [18:52:57.547022][RIGCTRL][trace] () : icom_set_vfo: line#2656 curr_vfo=Main
warning: [18:52:57.547022][RIGCTRL][debug] () : icom.c(2657):icom_set_vfo return(0)
warning: [18:52:57.547022][RIGCTRL][trace] () : rig_set_vfo: rig->state.current_vfo=Main
warning: [18:52:57.547772][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2701) trace
warning: [18:52:57.547772][RIGCTRL][debug] () : icom_get_freq called for Main, curr_vfo=Main
warning: [18:52:57.547772][RIGCTRL][debug] () : icom_get_freq: using vfo=Main
warning: [18:52:57.548523][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1359) trace
warning: [18:52:57.548523][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:57.549273][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:57.549273][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:57.549273][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:57.550023][RIGCTRL][debug] () : icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
warning: [18:52:57.550023][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:57.550023][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:57.550774][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:57.550774][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:57.550774][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:57.551525][RIGCTRL][debug] () : tcflush
warning: [18:52:57.551525][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:57.552275][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:57.552275][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:57.552275][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.553026][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.574040][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:57.574790][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:57.574790][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:57.574790][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.575541][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.586047][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:57.586800][RIGCTRL][trace] () : 0000 fe fe e0 50 03 00 40 07 21 00 fd ...P..@.!..
warning: [18:52:57.586800][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:57.587549][RIGCTRL][trace] () : icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
warning: [18:52:57.587549][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:57.587549][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:57.588300][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1370) trace
warning: [18:52:57.588300][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:57.589052][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:57.589801][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:57.589801][RIGCTRL][debug] () : from_bcd called
warning: [18:52:57.589801][RIGCTRL][debug] () : icom_get_freq exit vfo=Main, curr_vfo=Main
warning: [18:52:57.590551][RIGCTRL][debug] () : icom.c(1458):icom_get_freq return(0)
warning: [18:52:57.590551][RIGCTRL][trace] () : rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
icom.c(1458):icom_get_freq return(0)warning: [18:52:57.718050][RIGCTRL][trace] () : rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
warning: [18:52:57.718050][RIGCTRL][debug] () : rig.c(2728):rig_set_vfo return(0)
warning: [18:52:57.718801][RIGCTRL][debug] () : rig_set_freq called vfo=currVFO, freq=21074500
warning: [18:52:57.718801][RIGCTRL][trace] () : vfo_fixup: vfo=currVFO, vfo_curr=Main
warning: [18:52:57.718801][RIGCTRL][trace] () : vfo_fixup: Leaving currVFO alone
warning: [18:52:57.718801][RIGCTRL][trace] () : rig_set_freq: TARGETABLE_FREQ vfo=currVFO
warning: [18:52:57.719551][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(1842) trace
warning: [18:52:57.719551][RIGCTRL][debug] () : icom_set_freq called currVFO=21074500
warning: [18:52:57.719551][RIGCTRL][trace] () : icom_set_freq: currVFO asked for so vfo set to Main
warning: [18:52:57.720302][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1039) trace
warning: [18:52:57.720302][RIGCTRL][trace] () : icom_set_freq: set_vfo_curr=Main
warning: [18:52:57.720302][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:57.721052][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:57.721052][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:57.721052][RIGCTRL][debug] () : rig_get_freq(1994) called vfo=Main
warning: [18:52:57.721803][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:57.721803][RIGCTRL][debug] () : C:/Users/bill/src/hamlib/src/rig.c(2003) vfo=Main, curr_vfo=Main
warning: [18:52:57.721803][RIGCTRL][trace] () : rig_get_freq: Main cache hit age=3ms, freq=21074000
warning: [18:52:57.722553][RIGCTRL][debug] () : rig.c(2063):rig_get_freq return(0)
warning: [18:52:57.722553][RIGCTRL][debug] () : to_bcd called
warning: [18:52:57.722553][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:57.722553][RIGCTRL][debug] () : icom_transaction: cmd=0x05, subcmd=0xffffffff, payload_len=5
warning: [18:52:57.723303][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:57.723303][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:57.723303][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(11)
warning: [18:52:57.724054][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:57.724054][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:57.724054][RIGCTRL][debug] () : tcflush
warning: [18:52:57.724054][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:57.724805][RIGCTRL][trace] () : write_block(): TX 11 bytes
warning: [18:52:57.724805][RIGCTRL][trace] () : 0000 fe fe 50 e0 05 00 45 07 21 00 fd ..P...E.!..
warning: [18:52:57.725556][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.725556][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.746568][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:57.747320][RIGCTRL][trace] () : 0000 fe fe 50 e0 05 00 45 07 21 00 fd ..P...E.!..
warning: [18:52:57.747320][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:57.748070][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.748070][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.769835][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:57.770586][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:57.770586][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:57.878658][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:57.878658][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:57.879409][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:57.929693][RIGCTRL][debug] () : icom.c(1190):icom_set_freq return(0)
warning: [18:52:57.929693][RIGCTRL][debug] () : rig.c(1954):rig_set_freq return(0)
warning: [18:52:57.929693][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(5734) trace
warning: [18:52:57.930443][RIGCTRL][debug] () : rig.c(2616):rig_set_vfo entered
warning: [18:52:57.930443][RIGCTRL][debug] () : rig_set_vfo called vfo=Main
warning: [18:52:57.930443][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:57.931194][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:57.931194][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2675) trace
warning: [18:52:57.931194][RIGCTRL][debug] () : icom_set_vfo called vfo=Main
warning: [18:52:57.931945][RIGCTRL][trace] () : icom_set_vfo: line#2473
warning: [18:52:57.931945][RIGCTRL][trace] () : icom_set_vfo: line#2630
warning: [18:52:57.931945][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:57.931945][RIGCTRL][debug] () : icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
warning: [18:52:57.932695][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:57.932695][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:57.932695][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:57.932695][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:57.933445][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:57.933445][RIGCTRL][debug] () : tcflush
warning: [18:52:57.933445][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:57.934196][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:57.934196][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:57.934196][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.934947][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.955960][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:57.956711][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:57.956711][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:57.956711][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:57.957461][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:57.968719][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:57.968719][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:57.969469][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:57.969469][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:57.970220][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:57.970220][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:57.970971][RIGCTRL][trace] () : icom_set_vfo: line#2633
warning: [18:52:57.970971][RIGCTRL][trace] () : icom_set_vfo: line#2656 curr_vfo=Main
warning: [18:52:57.970971][RIGCTRL][debug] () : icom.c(2657):icom_set_vfo return(0)
warning: [18:52:57.971721][RIGCTRL][trace] () : rig_set_vfo: rig->state.current_vfo=Main
warning: [18:52:57.972472][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2701) trace
warning: [18:52:57.973222][RIGCTRL][debug] () : icom_get_freq called for Main, curr_vfo=Main
warning: [18:52:58.103060][RIGCTRL][debug] () : icom_get_freq: using vfo=Main
warning: [18:52:58.103060][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1359) trace
warning: [18:52:58.103811][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:58.103811][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:58.104562][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:58.104562][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:58.105311][RIGCTRL][debug] () : icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
warning: [18:52:58.105311][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:58.105311][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:58.106063][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:58.106063][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:58.106812][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:58.106812][RIGCTRL][debug] () : tcflush
warning: [18:52:58.107563][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:58.107563][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:58.107563][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:58.108314][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.108314][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.161599][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:58.162351][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:58.162351][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:58.162351][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.163101][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.173608][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:58.174358][RIGCTRL][trace] () : 0000 fe fe e0 50 03 00 45 07 21 00 fd ...P..E.!..
warning: [18:52:58.175108][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:58.175108][RIGCTRL][trace] () : icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
warning: [18:52:58.175108][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:58.175859][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:58.175859][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1370) trace
warning: [18:52:58.176609][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:58.176609][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:58.176609][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:58.176609][RIGCTRL][debug] () : from_bcd called
warning: [18:52:58.177361][RIGCTRL][debug] () : icom_get_freq exit vfo=Main, curr_vfo=Main
warning: [18:52:58.177361][RIGCTRL][debug] () : icom.c(1458):icom_get_freq return(0)
warning: [18:52:58.178111][RIGCTRL][trace] () : rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
icom.c(1458):icom_get_freq return(0)warning: [18:52:58.178861][RIGCTRL][trace] () : rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
warning: [18:52:58.178861][RIGCTRL][debug] () : rig.c(2728):rig_set_vfo return(0)
warning: [18:52:58.179612][RIGCTRL][debug] () : icom_set_mode called vfo=currVFO, mode=USB, width=-1, current_vfo=Main
warning: [18:52:58.264419][RIGCTRL][debug] () : frame.c(509):rig2icom_mode entered
warning: [18:52:58.264419][RIGCTRL][trace] () : rig2icom_mode: mode=4, width=-1
warning: [18:52:58.265170][RIGCTRL][trace] () : rig2icom_mode: width==RIG_PASSBAND_NOCHANGE
warning: [18:52:58.265170][RIGCTRL][debug] () : rig.c(2328):rig_get_mode entered
warning: [18:52:58.265170][RIGCTRL][trace] () : rig_get_mode: currVFO cache check age=86ms
warning: [18:52:58.265920][RIGCTRL][trace] () : rig_get_mode: cache miss age mode=1412ms, width=1412ms
warning: [18:52:58.265920][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2375) trace
warning: [18:52:58.265920][RIGCTRL][debug] () : icom_get_mode called vfo=currVFO
warning: [18:52:58.266670][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:58.266670][RIGCTRL][debug] () : icom_transaction: cmd=0x04, subcmd=0xffffffff, payload_len=0
warning: [18:52:58.267421][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:58.267421][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:58.267421][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:58.268171][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:58.268171][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:58.268171][RIGCTRL][debug] () : tcflush
warning: [18:52:58.268171][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:58.268922][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:58.268922][RIGCTRL][trace] () : 0000 fe fe 50 e0 04 fd ..P...
warning: [18:52:58.269672][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.269672][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.290686][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:58.290686][RIGCTRL][trace] () : 0000 fe fe 50 e0 04 fd ..P...
warning: [18:52:58.291436][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:58.291436][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.291436][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.302694][RIGCTRL][trace] () : read_string(): RX 8 characters
warning: [18:52:58.303445][RIGCTRL][trace] () : 0000 fe fe e0 50 04 01 01 fd ...P....
warning: [18:52:58.303445][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(8)
warning: [18:52:58.304195][RIGCTRL][trace] () : icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=01
warning: [18:52:58.304195][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:58.304195][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:58.304946][RIGCTRL][trace] () : icom_get_mode: modebuf[0]=0x04, modebuf[1]=0x01, mode_len=2
warning: [18:52:58.304946][RIGCTRL][debug] () : frame.c(642):icom2rig_mode entered
warning: [18:52:58.304946][RIGCTRL][trace] () : icom2rig_mode: mode=0x01, pd=1
warning: [18:52:58.305697][RIGCTRL][debug] () : rig.c(2558):rig_passband_wide entered
warning: [18:52:58.305697][RIGCTRL][debug] () : rig.c(2582):rig_passband_wide return(0)
warning: [18:52:58.306447][RIGCTRL][debug] () : rig.c(2455):rig_passband_normal entered
warning: [18:52:58.306447][RIGCTRL][debug] () : rig_passband_normal: return filter#0, width=2400
warning: [18:52:58.306447][RIGCTRL][debug] () : rig.c(2475):rig_passband_normal return(2400)
warning: [18:52:58.307197][RIGCTRL][debug] () : icom_get_dsp_flt called, mode=USB
warning: [18:52:58.307197][RIGCTRL][trace] () : icom_get_mode: vfo=currVFO returning mode=USB, width not available
warning: [18:52:58.307197][RIGCTRL][debug] () : icom.c(2329):icom_get_mode return(0)
warning: [18:52:58.435533][RIGCTRL][trace] () : rig_get_mode: retcode after get_mode=0
warning: [18:52:58.435533][RIGCTRL][trace] () : rig_get_mode(2419): debug
warning: [18:52:58.436284][RIGCTRL][trace] () : rig_get_mode(2427): debug
warning: [18:52:58.436284][RIGCTRL][debug] () : rig.c(2455):rig_passband_normal entered
warning: [18:52:58.436284][RIGCTRL][debug] () : rig_passband_normal: return filter#0, width=2400
warning: [18:52:58.437035][RIGCTRL][debug] () : rig.c(2475):rig_passband_normal return(2400)
warning: [18:52:58.437035][RIGCTRL][debug] () : rig.c(1407):set_cache_mode entered
warning: [18:52:58.437035][RIGCTRL][debug] () : rig.c(1471):set_cache_mode return(0)
warning: [18:52:58.437785][RIGCTRL][debug] () : rig.c(2434):rig_get_mode return(0)
warning: [18:52:58.437785][RIGCTRL][debug] () : frame.c(633):rig2icom_mode return(0)
warning: [18:52:58.437785][RIGCTRL][debug] () : icom_set_mode: icmode=1, icmode_ext=-1
warning: [18:52:58.438536][RIGCTRL][debug] () : icom_set_mode: #2 icmode=1, icmode_ext=-1
warning: [18:52:58.438536][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:58.439286][RIGCTRL][debug] () : icom_transaction: cmd=0x06, subcmd=0x01, payload_len=0
warning: [18:52:58.439286][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:58.440036][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:58.440036][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:58.440787][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:58.440787][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:58.440787][RIGCTRL][debug] () : tcflush
warning: [18:52:58.441537][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:58.441537][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:58.442288][RIGCTRL][trace] () : 0000 fe fe 50 e0 06 01 fd ..P....
warning: [18:52:58.442288][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.442288][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.464052][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:58.464803][RIGCTRL][trace] () : 0000 fe fe 50 e0 06 01 fd ..P....
warning: [18:52:58.464803][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:58.464803][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.465553][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.476811][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:58.476811][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:58.477561][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:58.477561][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:58.477561][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:58.478312][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:58.478312][RIGCTRL][debug] () : icom.c(2034):icom_set_mode return(0)
warning: [18:52:58.478312][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(5748) trace
warning: [18:52:58.479063][RIGCTRL][debug] () : rig.c(2616):rig_set_vfo entered
warning: [18:52:58.479063][RIGCTRL][debug] () : rig_set_vfo called vfo=Main
warning: [18:52:58.479063][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:58.479063][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Main
warning: [18:52:58.479813][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2675) trace
warning: [18:52:58.479813][RIGCTRL][debug] () : icom_set_vfo called vfo=Main
warning: [18:52:58.596891][RIGCTRL][trace] () : icom_set_vfo: line#2473
warning: [18:52:58.596891][RIGCTRL][trace] () : icom_set_vfo: line#2630
warning: [18:52:58.597642][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:58.597642][RIGCTRL][debug] () : icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
warning: [18:52:58.597642][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:58.598392][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:58.598392][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:58.598392][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:58.599143][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:58.599143][RIGCTRL][debug] () : tcflush
warning: [18:52:58.599893][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:58.599893][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:58.599893][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:58.600644][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.600644][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.622409][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:58.623159][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:58.623159][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:58.623159][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.623909][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.623909][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:58.624660][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:58.624660][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:58.624660][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:58.625410][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:58.625410][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:58.625410][RIGCTRL][trace] () : icom_set_vfo: line#2633
warning: [18:52:58.625410][RIGCTRL][trace] () : icom_set_vfo: line#2656 curr_vfo=Main
warning: [18:52:58.626161][RIGCTRL][debug] () : icom.c(2657):icom_set_vfo return(0)
warning: [18:52:58.626161][RIGCTRL][trace] () : rig_set_vfo: rig->state.current_vfo=Main
warning: [18:52:58.626911][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2701) trace
warning: [18:52:58.626911][RIGCTRL][debug] () : icom_get_freq called for Main, curr_vfo=Main
warning: [18:52:58.626911][RIGCTRL][debug] () : icom_get_freq: using vfo=Main
warning: [18:52:58.627662][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1359) trace
warning: [18:52:58.627662][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:58.628412][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:58.628412][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:58.628412][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:58.629163][RIGCTRL][debug] () : icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
warning: [18:52:58.629163][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:58.629914][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:58.629914][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:58.629914][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:58.630664][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:58.789771][RIGCTRL][debug] () : tcflush
warning: [18:52:58.790521][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:58.790521][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:58.791272][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:58.791272][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.791272][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.813036][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:58.813036][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:58.813787][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:58.813787][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:58.813787][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:58.814536][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:58.815287][RIGCTRL][trace] () : 0000 fe fe e0 50 03 00 45 07 21 00 fd ...P..E.!..
warning: [18:52:58.815287][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:58.815287][RIGCTRL][trace] () : icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
warning: [18:52:58.816038][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:58.816038][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:58.816789][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1370) trace
warning: [18:52:58.816789][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:58.816789][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:58.817539][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:58.817539][RIGCTRL][debug] () : from_bcd called
warning: [18:52:58.817539][RIGCTRL][debug] () : icom_get_freq exit vfo=Main, curr_vfo=Main
warning: [18:52:58.818290][RIGCTRL][debug] () : icom.c(1458):icom_get_freq return(0)
warning: [18:52:58.818290][RIGCTRL][trace] () : rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
icom.c(1458):icom_get_freq return(0)warning: [18:52:58.819040][RIGCTRL][trace] () : rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
warning: [18:52:58.819040][RIGCTRL][debug] () : rig.c(2728):rig_set_vfo return(0)
warning: [18:52:58.819040][RIGCTRL][debug] () : icom.c(5768):icom_set_split_freq_mode return(0)
warning: [18:52:58.819791][RIGCTRL][debug] () : rig.c(4413):rig_set_split_freq_mode return(0)
Second trace extract:

warning: [18:52:44.717724][RIGCTRL][trace] C:\Users\bill\src\k1jt\wsjtx\Transceiver\HamlibTransceiver.cpp(989) do_tx_frequency: rig_set_split_freq_mode freq=14074500 mode = USB
warning: [18:52:44.717724][RIGCTRL][debug] () : rig.c(4363):rig_set_split_freq_mode entered
warning: [18:52:44.718474][RIGCTRL][trace] () : vfo_fixup: vfo=TX, vfo_curr=Main
warning: [18:52:44.718474][RIGCTRL][trace] () : vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
warning: [18:52:44.718474][RIGCTRL][debug] () : rig_set_split_freq_mode: vfo=Sub, tx_freq=14074500, tx_mode=USB, tx_width=-1
warning: [18:52:44.719225][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(4385) trace
warning: [18:52:44.719225][RIGCTRL][debug] () : icom_set_split_freq_mode called vfo=Sub
warning: [18:52:44.719975][RIGCTRL][debug] () : icom_set_split_freq_mode: curr_vfo=Main
warning: [18:52:44.719975][RIGCTRL][debug] () : icom_set_split_freq_mode: before get_split_vfos rx_vfo=currVFO tx_vfo=Sub
warning: [18:52:44.720726][RIGCTRL][debug] () : icom_get_split_vfos called
warning: [18:52:44.720726][RIGCTRL][trace] () : icom_get_split_vfos: VFO_HAS_MAIN_SUB_ONLY, split=1, rx=Main, tx=Sub
warning: [18:52:44.720726][RIGCTRL][debug] () : icom.c(4920):icom_get_split_vfos return(0)
warning: [18:52:44.721476][RIGCTRL][debug] () : icom_set_split_freq_mode: after get_split_vfos rx_vfo=currVFO tx_vfo=Sub
warning: [18:52:44.851313][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(5721) trace
warning: [18:52:44.852064][RIGCTRL][debug] () : rig.c(2616):rig_set_vfo entered
warning: [18:52:44.852064][RIGCTRL][debug] () : rig_set_vfo called vfo=Sub
warning: [18:52:44.852064][RIGCTRL][debug] () : rig_set_vfo ********************** called vfo=Sub
warning: [18:52:44.852064][RIGCTRL][trace] () : vfo_fixup: vfo=Sub, vfo_curr=Main
warning: [18:52:44.852814][RIGCTRL][trace] () : vfo_fixup: vfo=Sub, vfo_curr=Main
warning: [18:52:44.852814][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2675) trace
warning: [18:52:44.852814][RIGCTRL][debug] () : icom_set_vfo called vfo=Sub
warning: [18:52:44.852814][RIGCTRL][trace] () : icom_set_vfo: line#2473
warning: [18:52:44.853564][RIGCTRL][trace] () : icom_set_vfo: Sub asked for, ended up with vfo=Sub
warning: [18:52:44.853564][RIGCTRL][trace] () : icom_set_vfo: line#2630
warning: [18:52:44.853564][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:44.853564][RIGCTRL][debug] () : icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
warning: [18:52:44.854315][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:44.854315][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:44.854315][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:44.855065][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:44.855065][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:44.855065][RIGCTRL][debug] () : tcflush
warning: [18:52:44.855065][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:44.855816][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:44.855816][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d1 fd ..P....
warning: [18:52:44.856566][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:44.856566][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:44.899345][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:44.899345][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d1 fd ..P....
warning: [18:52:44.900096][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:44.900096][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:44.900096][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:44.911353][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:44.911353][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:44.912104][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:44.912104][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:44.912104][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:44.912854][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:44.912854][RIGCTRL][trace] () : icom_set_vfo: line#2633
warning: [18:52:44.912854][RIGCTRL][trace] () : icom_set_vfo: line#2656 curr_vfo=Sub
warning: [18:52:44.912854][RIGCTRL][debug] () : icom.c(2657):icom_set_vfo return(0)
warning: [18:52:44.913605][RIGCTRL][trace] () : rig_set_vfo: rig->state.current_vfo=Sub
warning: [18:52:44.913605][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2701) trace
warning: [18:52:44.913605][RIGCTRL][debug] () : icom_get_freq called for Sub, curr_vfo=Sub
warning: [18:52:44.914355][RIGCTRL][debug] () : icom_get_freq: using vfo=Sub
warning: [18:52:44.914355][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1359) trace
warning: [18:52:45.031434][RIGCTRL][trace] () : set_vfo_curr: vfo=Sub, curr_vfo=Sub
warning: [18:52:45.031434][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Sub
warning: [18:52:45.032184][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:45.032184][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.032184][RIGCTRL][debug] () : icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
warning: [18:52:45.032935][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.032935][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.033687][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:45.033687][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:45.034436][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:45.034436][RIGCTRL][debug] () : tcflush
warning: [18:52:45.034436][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:45.035186][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:45.035186][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:45.035939][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.035939][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.057701][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:45.058452][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:45.058452][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:45.058452][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.059202][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.069709][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:45.070459][RIGCTRL][trace] () : 0000 fe fe e0 50 03 00 45 07 21 00 fd ...P..E.!..
warning: [18:52:45.070459][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:45.071210][RIGCTRL][trace] () : icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
warning: [18:52:45.071210][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:45.071961][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:45.071961][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1370) trace
warning: [18:52:45.071961][RIGCTRL][trace] () : set_vfo_curr: vfo=Sub, curr_vfo=Sub
warning: [18:52:45.072711][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Sub
warning: [18:52:45.072711][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:45.072711][RIGCTRL][debug] () : from_bcd called
warning: [18:52:45.073462][RIGCTRL][debug] () : icom_get_freq exit vfo=Sub, curr_vfo=Sub
warning: [18:52:45.073462][RIGCTRL][debug] () : icom.c(1458):icom_get_freq return(0)
warning: [18:52:45.073462][RIGCTRL][trace] () : rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1458):icom_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1458):icom_get_freq return(0)
icom.c(1458):icom_get_freq return(0)warning: [18:52:45.074213][RIGCTRL][trace] () : rig_set_vfo: return 0, vfo=Sub, curr_vfo=Sub
warning: [18:52:45.074963][RIGCTRL][debug] () : rig.c(2728):rig_set_vfo return(0)
warning: [18:52:45.074963][RIGCTRL][debug] () : rig_set_freq called vfo=currVFO, freq=14074500
warning: [18:52:45.074963][RIGCTRL][trace] () : vfo_fixup: vfo=currVFO, vfo_curr=Sub
warning: [18:52:45.075713][RIGCTRL][trace] () : vfo_fixup: Leaving currVFO alone
warning: [18:52:45.075713][RIGCTRL][trace] () : rig_set_freq: TARGETABLE_FREQ vfo=currVFO
warning: [18:52:45.207052][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(1842) trace
warning: [18:52:45.207802][RIGCTRL][debug] () : icom_set_freq called currVFO=14074500
warning: [18:52:45.207802][RIGCTRL][trace] () : icom_set_freq: currVFO asked for so vfo set to Sub
warning: [18:52:45.207802][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1039) trace
warning: [18:52:45.208555][RIGCTRL][trace] () : icom_set_freq: set_vfo_curr=Sub
warning: [18:52:45.208555][RIGCTRL][trace] () : set_vfo_curr: vfo=Sub, curr_vfo=Sub
warning: [18:52:45.208555][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Sub
warning: [18:52:45.209302][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:45.209302][RIGCTRL][debug] () : rig_get_freq(1994) called vfo=Sub
warning: [18:52:45.209302][RIGCTRL][trace] () : vfo_fixup: vfo=Sub, vfo_curr=Sub
warning: [18:52:45.209302][RIGCTRL][debug] () : C:/Users/bill/src/hamlib/src/rig.c(2003) vfo=Sub, curr_vfo=Sub
warning: [18:52:45.210053][RIGCTRL][trace] () : rig_get_freq: Sub cache hit age=135ms, freq=21074500
warning: [18:52:45.210053][RIGCTRL][debug] () : rig.c(2063):rig_get_freq return(0)
warning: [18:52:45.210053][RIGCTRL][debug] () : to_bcd called
warning: [18:52:45.210053][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.210803][RIGCTRL][debug] () : icom_transaction: cmd=0x05, subcmd=0xffffffff, payload_len=5
warning: [18:52:45.210803][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.210803][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.210803][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(11)
warning: [18:52:45.211554][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:45.211554][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:45.211554][RIGCTRL][debug] () : tcflush
warning: [18:52:45.211554][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:45.212304][RIGCTRL][trace] () : write_block(): TX 11 bytes
warning: [18:52:45.212304][RIGCTRL][trace] () : 0000 fe fe 50 e0 05 00 45 07 14 00 fd ..P...E....
warning: [18:52:45.213056][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.213056][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.234070][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:45.234823][RIGCTRL][trace] () : 0000 fe fe 50 e0 05 00 45 07 14 00 fd ..P...E....
warning: [18:52:45.235570][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:45.235570][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.235570][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.257336][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:45.257336][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:45.258085][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:45.258085][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:45.258085][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:45.258835][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:45.309869][RIGCTRL][debug] () : icom.c(1190):icom_set_freq return(0)
warning: [18:52:45.309869][RIGCTRL][debug] () : rig.c(1954):rig_set_freq return(0)
warning: [18:52:45.310620][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(5734) trace
warning: [18:52:45.311370][RIGCTRL][debug] () : rig.c(2616):rig_set_vfo entered
warning: [18:52:45.312121][RIGCTRL][debug] () : rig_set_vfo called vfo=Sub
warning: [18:52:45.398429][RIGCTRL][debug] () : rig_set_vfo ********************** called vfo=Sub
warning: [18:52:45.398429][RIGCTRL][trace] () : vfo_fixup: vfo=Sub, vfo_curr=Sub
warning: [18:52:45.399180][RIGCTRL][trace] () : vfo_fixup: vfo=Sub, vfo_curr=Sub
warning: [18:52:45.399180][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2675) trace
warning: [18:52:45.399180][RIGCTRL][debug] () : icom_set_vfo called vfo=Sub
warning: [18:52:45.399180][RIGCTRL][trace] () : icom_set_vfo: line#2473
warning: [18:52:45.399930][RIGCTRL][trace] () : icom_set_vfo: Sub asked for, ended up with vfo=Sub
warning: [18:52:45.399930][RIGCTRL][trace] () : icom_set_vfo: line#2630
warning: [18:52:45.399930][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.400681][RIGCTRL][debug] () : icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
warning: [18:52:45.400681][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.400681][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.401432][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:45.401432][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:45.402182][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:45.402182][RIGCTRL][debug] () : tcflush
warning: [18:52:45.402182][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:45.402932][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:45.402932][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d1 fd ..P....
warning: [18:52:45.402932][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.403683][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.414191][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:45.414940][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d1 fd ..P....
warning: [18:52:45.414940][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:45.415691][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.415691][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.437456][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:45.437456][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:45.438206][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:45.438206][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:45.438206][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:45.438957][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:45.438957][RIGCTRL][trace] () : icom_set_vfo: line#2633
warning: [18:52:45.439707][RIGCTRL][trace] () : icom_set_vfo: line#2656 curr_vfo=Sub
warning: [18:52:45.439707][RIGCTRL][debug] () : icom.c(2657):icom_set_vfo return(0)
warning: [18:52:45.439707][RIGCTRL][trace] () : rig_set_vfo: rig->state.current_vfo=Sub
warning: [18:52:45.440457][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2701) trace
warning: [18:52:45.440457][RIGCTRL][debug] () : icom_get_freq called for Sub, curr_vfo=Sub
warning: [18:52:45.440457][RIGCTRL][debug] () : icom_get_freq: using vfo=Sub
warning: [18:52:45.441208][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1359) trace
warning: [18:52:45.441208][RIGCTRL][trace] () : set_vfo_curr: vfo=Sub, curr_vfo=Sub
warning: [18:52:45.441208][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Sub
warning: [18:52:45.441958][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:45.441958][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.441958][RIGCTRL][debug] () : icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
warning: [18:52:45.553033][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.553033][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.553784][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:45.553784][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:45.554534][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:45.554534][RIGCTRL][debug] () : tcflush
warning: [18:52:45.554534][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:45.555284][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:45.555284][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:45.555284][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.556035][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.577049][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:45.577049][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:45.577799][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:45.577799][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.578550][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.589808][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:45.589808][RIGCTRL][trace] () : 0000 fe fe e0 50 03 00 45 07 14 00 fd ...P..E....
warning: [18:52:45.589808][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:45.590558][RIGCTRL][trace] () : icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
warning: [18:52:45.590558][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:45.591309][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:45.591309][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1370) trace
warning: [18:52:45.591309][RIGCTRL][trace] () : set_vfo_curr: vfo=Sub, curr_vfo=Sub
warning: [18:52:45.592058][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Sub
warning: [18:52:45.592058][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:45.592058][RIGCTRL][debug] () : from_bcd called
warning: [18:52:45.592809][RIGCTRL][debug] () : icom_get_freq exit vfo=Sub, curr_vfo=Sub
warning: [18:52:45.592809][RIGCTRL][debug] () : icom.c(1458):icom_get_freq return(0)
warning: [18:52:45.592809][RIGCTRL][trace] () : rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1458):icom_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1458):icom_get_freq return(0)
icom.c(1458):icom_get_freq return(0)warning: [18:52:45.593560][RIGCTRL][trace] () : rig_set_vfo: return 0, vfo=Sub, curr_vfo=Sub
warning: [18:52:45.594311][RIGCTRL][debug] () : rig.c(2728):rig_set_vfo return(0)
warning: [18:52:45.594311][RIGCTRL][debug] () : icom_set_mode called vfo=currVFO, mode=USB, width=-1, current_vfo=Sub
warning: [18:52:45.594311][RIGCTRL][debug] () : frame.c(509):rig2icom_mode entered
warning: [18:52:45.595061][RIGCTRL][trace] () : rig2icom_mode: mode=4, width=-1
warning: [18:52:45.595061][RIGCTRL][trace] () : rig2icom_mode: width==RIG_PASSBAND_NOCHANGE
warning: [18:52:45.595061][RIGCTRL][debug] () : rig.c(2328):rig_get_mode entered
warning: [18:52:45.595812][RIGCTRL][trace] () : rig_get_mode: currVFO cache check age=1ms
warning: [18:52:45.595812][RIGCTRL][trace] () : rig_get_mode: cache miss age mode=23519ms, width=23519ms
warning: [18:52:45.595812][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2375) trace
warning: [18:52:45.770678][RIGCTRL][debug] () : icom_get_mode called vfo=currVFO
warning: [18:52:45.770678][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.771429][RIGCTRL][debug] () : icom_transaction: cmd=0x04, subcmd=0xffffffff, payload_len=0
warning: [18:52:45.771429][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.772180][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.772180][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:45.772180][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:45.772931][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:45.772931][RIGCTRL][debug] () : tcflush
warning: [18:52:45.772931][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:45.772931][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:45.773682][RIGCTRL][trace] () : 0000 fe fe 50 e0 04 fd ..P...
warning: [18:52:45.773682][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.774431][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.784938][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:45.785690][RIGCTRL][trace] () : 0000 fe fe 50 e0 04 fd ..P...
warning: [18:52:45.785690][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:45.786438][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.786438][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.797696][RIGCTRL][trace] () : read_string(): RX 8 characters
warning: [18:52:45.798446][RIGCTRL][trace] () : 0000 fe fe e0 50 04 01 01 fd ...P....
warning: [18:52:45.798446][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(8)
warning: [18:52:45.798446][RIGCTRL][trace] () : icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=01
warning: [18:52:45.799197][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:45.799197][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:45.799197][RIGCTRL][trace] () : icom_get_mode: modebuf[0]=0x04, modebuf[1]=0x01, mode_len=2
warning: [18:52:45.799197][RIGCTRL][debug] () : frame.c(642):icom2rig_mode entered
warning: [18:52:45.799947][RIGCTRL][trace] () : icom2rig_mode: mode=0x01, pd=1
warning: [18:52:45.799947][RIGCTRL][debug] () : rig.c(2558):rig_passband_wide entered
warning: [18:52:45.799947][RIGCTRL][debug] () : rig.c(2582):rig_passband_wide return(0)
warning: [18:52:45.800698][RIGCTRL][debug] () : rig.c(2455):rig_passband_normal entered
warning: [18:52:45.800698][RIGCTRL][debug] () : rig_passband_normal: return filter#0, width=2400
warning: [18:52:45.800698][RIGCTRL][debug] () : rig.c(2475):rig_passband_normal return(2400)
warning: [18:52:45.801448][RIGCTRL][debug] () : icom_get_dsp_flt called, mode=USB
warning: [18:52:45.801448][RIGCTRL][trace] () : icom_get_mode: vfo=currVFO returning mode=USB, width not available
warning: [18:52:45.802199][RIGCTRL][debug] () : icom.c(2329):icom_get_mode return(0)
warning: [18:52:45.802199][RIGCTRL][trace] () : rig_get_mode: retcode after get_mode=0
warning: [18:52:45.802199][RIGCTRL][trace] () : rig_get_mode(2419): debug
warning: [18:52:45.802950][RIGCTRL][trace] () : rig_get_mode(2427): debug
warning: [18:52:45.802950][RIGCTRL][debug] () : rig.c(2455):rig_passband_normal entered
warning: [18:52:45.802950][RIGCTRL][debug] () : rig_passband_normal: return filter#0, width=2400
warning: [18:52:45.803701][RIGCTRL][debug] () : rig.c(2475):rig_passband_normal return(2400)
warning: [18:52:45.803701][RIGCTRL][debug] () : rig.c(1407):set_cache_mode entered
warning: [18:52:45.923781][RIGCTRL][debug] () : rig.c(1471):set_cache_mode return(0)
warning: [18:52:45.923781][RIGCTRL][debug] () : rig.c(2434):rig_get_mode return(0)
warning: [18:52:45.924531][RIGCTRL][debug] () : frame.c(633):rig2icom_mode return(0)
warning: [18:52:45.924531][RIGCTRL][debug] () : icom_set_mode: icmode=1, icmode_ext=-1
warning: [18:52:45.924531][RIGCTRL][debug] () : icom_set_mode: #2 icmode=1, icmode_ext=-1
warning: [18:52:45.924531][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.925282][RIGCTRL][debug] () : icom_transaction: cmd=0x06, subcmd=0x01, payload_len=0
warning: [18:52:45.925282][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.925282][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.926032][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:45.926032][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:45.926032][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:45.926783][RIGCTRL][debug] () : tcflush
warning: [18:52:45.926783][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:45.926783][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:45.927533][RIGCTRL][trace] () : 0000 fe fe 50 e0 06 01 fd ..P....
warning: [18:52:45.927533][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.927533][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.959805][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:45.959805][RIGCTRL][trace] () : 0000 fe fe 50 e0 06 01 fd ..P....
warning: [18:52:45.960555][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:45.960555][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:45.960555][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:45.971813][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:45.971813][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:45.972563][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:45.972563][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:45.973313][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:45.973313][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:45.973313][RIGCTRL][debug] () : icom.c(2034):icom_set_mode return(0)
warning: [18:52:45.974065][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(5748) trace
warning: [18:52:45.974065][RIGCTRL][debug] () : rig.c(2616):rig_set_vfo entered
warning: [18:52:45.974065][RIGCTRL][debug] () : rig_set_vfo called vfo=Main
warning: [18:52:45.974815][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Sub
warning: [18:52:45.974815][RIGCTRL][trace] () : vfo_fixup: vfo=Main, vfo_curr=Sub
warning: [18:52:45.974815][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2675) trace
warning: [18:52:45.975565][RIGCTRL][debug] () : icom_set_vfo called vfo=Main
warning: [18:52:45.975565][RIGCTRL][trace] () : icom_set_vfo: line#2473
warning: [18:52:45.975565][RIGCTRL][trace] () : icom_set_vfo: line#2630
warning: [18:52:45.975565][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:45.976316][RIGCTRL][debug] () : icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
warning: [18:52:45.976316][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:45.976316][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:45.977066][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(7)
warning: [18:52:46.071631][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:46.071631][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:46.072381][RIGCTRL][debug] () : tcflush
warning: [18:52:46.072381][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:46.073131][RIGCTRL][trace] () : write_block(): TX 7 bytes
warning: [18:52:46.073131][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:46.073882][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:46.073882][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:46.085140][RIGCTRL][trace] () : read_string(): RX 7 characters
warning: [18:52:46.085140][RIGCTRL][trace] () : 0000 fe fe 50 e0 07 d0 fd ..P....
warning: [18:52:46.085889][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(7)
warning: [18:52:46.085889][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:46.086643][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:46.107653][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:46.108405][RIGCTRL][trace] () : 0000 fe fe e0 50 fb fd ...P..
warning: [18:52:46.108405][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:46.108405][RIGCTRL][trace] () : icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
warning: [18:52:46.109155][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:46.109155][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:46.109905][RIGCTRL][trace] () : icom_set_vfo: line#2633
warning: [18:52:46.109905][RIGCTRL][trace] () : icom_set_vfo: line#2656 curr_vfo=Main
warning: [18:52:46.109905][RIGCTRL][debug] () : icom.c(2657):icom_set_vfo return(0)
warning: [18:52:46.110655][RIGCTRL][trace] () : rig_set_vfo: rig->state.current_vfo=Main
warning: [18:52:46.110655][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/src/rig.c(2701) trace
warning: [18:52:46.110655][RIGCTRL][debug] () : icom_get_freq called for Main, curr_vfo=Main
warning: [18:52:46.111408][RIGCTRL][debug] () : icom_get_freq: using vfo=Main
warning: [18:52:46.111408][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1359) trace
warning: [18:52:46.111408][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:46.111408][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:46.112158][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:46.112158][RIGCTRL][debug] () : frame.c(402):icom_transaction entered
warning: [18:52:46.112158][RIGCTRL][debug] () : icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
warning: [18:52:46.112909][RIGCTRL][debug] () : frame.c(139):icom_one_transaction entered
warning: [18:52:46.112909][RIGCTRL][debug] () : frame.c(54):make_cmd_frame entered
warning: [18:52:46.112909][RIGCTRL][debug] () : frame.c(88):make_cmd_frame return(6)
warning: [18:52:46.112909][RIGCTRL][trace] () : rig_flush: called for serial device
warning: [18:52:46.113657][RIGCTRL][debug] () : serial.c(642):serial_flush entered
warning: [18:52:46.113657][RIGCTRL][debug] () : tcflush
warning: [18:52:46.113657][RIGCTRL][debug] () : serial.c(674):serial_flush return(0)
warning: [18:52:46.114408][RIGCTRL][trace] () : write_block(): TX 6 bytes
warning: [18:52:46.114408][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:46.114408][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:46.115158][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:46.146679][RIGCTRL][trace] () : read_string(): RX 6 characters
warning: [18:52:46.244245][RIGCTRL][trace] () : 0000 fe fe 50 e0 03 fd ..P...
warning: [18:52:46.244245][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(6)
warning: [18:52:46.244996][RIGCTRL][debug] () : frame.c(452):read_icom_frame entered
warning: [18:52:46.244996][RIGCTRL][trace] () : read_string called, rxmax=80
warning: [18:52:46.245748][RIGCTRL][trace] () : read_string(): RX 11 characters
warning: [18:52:46.246497][RIGCTRL][trace] () : 0000 fe fe e0 50 03 00 40 07 14 00 fd ...P..@....
warning: [18:52:46.246497][RIGCTRL][debug] () : frame.c(486):read_icom_frame return(11)
warning: [18:52:46.247247][RIGCTRL][trace] () : icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
warning: [18:52:46.247998][RIGCTRL][debug] () : frame.c(380):icom_one_transaction return(0)
warning: [18:52:46.248748][RIGCTRL][debug] () : frame.c(432):icom_transaction return(0)
warning: [18:52:46.248748][RIGCTRL][trace] () : C:/Users/bill/src/hamlib/rigs/icom/icom.c(1370) trace
warning: [18:52:46.249498][RIGCTRL][trace] () : set_vfo_curr: vfo=Main, curr_vfo=Main
warning: [18:52:46.249498][RIGCTRL][trace] () : set_vfo_curr: curr_vfo now=Main
warning: [18:52:46.249498][RIGCTRL][debug] () : icom.c(8846):set_vfo_curr return(0)
warning: [18:52:46.250249][RIGCTRL][debug] () : from_bcd called
warning: [18:52:46.250249][RIGCTRL][debug] () : icom_get_freq exit vfo=Main, curr_vfo=Main
warning: [18:52:46.250249][RIGCTRL][debug] () : icom.c(1458):icom_get_freq return(0)
warning: [18:52:46.251000][RIGCTRL][trace] () : rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1458):icom_get_freq return(0)
icom.c(1458):icom_get_freq return(0)warning: [18:52:46.252501][RIGCTRL][trace] () : rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
warning: [18:52:46.253251][RIGCTRL][debug] () : rig.c(2728):rig_set_vfo return(0)
warning: [18:52:46.254002][RIGCTRL][debug] () : icom.c(5768):icom_set_split_freq_mode return(0)
warning: [18:52:46.254002][RIGCTRL][debug] () : rig.c(4413):rig_set_split_freq_mode return(0)
73
Bill
G4WJS.

Hamlib-developer mailing list
Hamlib-developer@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/hamlib-developer

@mdblack98
Copy link
Contributor Author

mdblack98 commented Aug 30, 2021 •
edited

Log shows that split had not been requested before calling set_split_freq_mode
Does seem that if this is done we should set split if it's off. Otherwise we should be returning an error.
So we'll take the smoother operation path.

@mdblack98 mdblack98 added the critical A problem for common operations with WSJT-X, GPredict, RigPi, etc. label Aug 30, 2021
mdblack98 added a commit that referenced this issue Aug 30, 2021
@mdblack98
If set_split_freq_mode is called when split=off we'll turn on split b…
f65022b 
…ased on current vfo selection

#764
wutje pushed a commit to wutje/Hamlib that referenced this issue Sep 4, 2021
@mdblack98 @wutje
If set_split_freq_mode is called when split=off we'll turn on split b…
ecf53e2 
…ased on current vfo selection

Hamlib#764
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug critical A problem for common operations with WSJT-X, GPredict, RigPi, etc. needs test Patches have been submitted but need testing to close issue
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
1 participant
@mdblack98