IC-756 split mode stays on VFOB after #665
Labels
bug
critical
A problem for common operations with WSJT-X, GPredict, RigPi, etc.
JTDX
Bugs affecting JTDX operations
needs test
Patches have been submitted but need testing to close issue
WSJTX
Bugs affecting WSTJ-X operations
[2021-04-14 23:42:31.869233][00:00:19.557463][RIGCTRL:trace] #: 2 Transceiver::TransceiverState(online: yes Frequency {7074000Hz, 0Hz} Mode: 3; SPLIT: off; PTT: off)
[2021-04-14 23:42:31.869233][00:00:19.557492][RIGCTRL:trace] #: 3 Transceiver::TransceiverState(online: yes Frequency {7074000Hz, 7074500Hz} Mode: 3; SPLIT: on; PTT: off)
[2021-04-14 23:42:31.869233][00:00:19.557518][RIGCTRL:trace] txf: 7074500 reversed: false
[2021-04-14 23:42:31.869233][00:00:19.557533][RIGCTRL:trace] RX VFO=Main TX VFO=Sub
[2021-04-14 23:42:31.869233][00:00:19.557552][RIGCTRL:trace] rig_set_split_vfo split=1
[2021-04-14 23:42:31.869233][00:00:19.557590][RIGCTRL:debug] rig.c(4227):rig_set_split_vfo entered
[2021-04-14 23:42:31.869233][00:00:19.557614][RIGCTRL:trace] vfo_fixup: vfo=currVFO
[2021-04-14 23:42:31.869233][00:00:19.557634][RIGCTRL:trace] vfo_fixup: Leaving currVFO alone
[2021-04-14 23:42:31.869233][00:00:19.557665][RIGCTRL:debug] icom_set_split_vfo called vfo='currVFO', split=1, tx_vfo=Sub, curr_vfo=Main
[2021-04-14 23:42:31.869233][00:00:19.557688][RIGCTRL:trace] icom_set_split_vfo: vfo clause 4
[2021-04-14 23:42:31.869233][00:00:19.557709][RIGCTRL:trace] icom_set_split_vfo: set_vfo because tx_vfo=Sub
[2021-04-14 23:42:31.869233][00:00:19.557731][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:31.869233][00:00:19.557757][RIGCTRL:debug] icom_transaction: cmd=0x0f, subcmd=0x01, payload_len=0
[2021-04-14 23:42:31.869233][00:00:19.557780][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:31.869233][00:00:19.557803][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:31.869985][00:00:19.557827][RIGCTRL:debug] frame.c(90):make_cmd_frame return(7)
[2021-04-14 23:42:31.869985][00:00:19.557848][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:31.869985][00:00:19.557869][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:31.869985][00:00:19.557886][RIGCTRL:debug] tcflush
[2021-04-14 23:42:31.869985][00:00:19.557937][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:31.869985][00:00:19.557955][RIGCTRL:debug] write_block called
[2021-04-14 23:42:31.869985][00:00:19.558066][RIGCTRL:trace] write_block(): TX 7 bytes
[2021-04-14 23:42:31.869985][00:00:19.558089][RIGCTRL:trace] 0000 fe fe 50 e0 0f 01 fd ..P....
[2021-04-14 23:42:31.869985][00:00:19.558108][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.869985][00:00:19.558125][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:31.870735][00:00:19.558931][SYSLOG:trace] #: 2 Transceiver::TransceiverState(online: yes Frequency {7074000Hz, 7074000Hz} Mode: 3; SPLIT: unknown; PTT: off)
[2021-04-14 23:42:31.890999][00:00:19.579220][RIGCTRL:trace] read_string(): RX 7 characters
[2021-04-14 23:42:31.890999][00:00:19.579259][RIGCTRL:trace] 0000 fe fe 50 e0 0f 01 fd ..P....
[2021-04-14 23:42:31.890999][00:00:19.579284][RIGCTRL:debug] frame.c(409):read_icom_frame return(7)
[2021-04-14 23:42:31.890999][00:00:19.579305][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.890999][00:00:19.579325][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:31.890999][00:00:19.579571][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:31.891748][00:00:19.579596][RIGCTRL:trace] 0000 fe fe e0 50 fb fd ...P..
[2021-04-14 23:42:31.891748][00:00:19.579619][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:31.891748][00:00:19.579645][RIGCTRL:trace] icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
[2021-04-14 23:42:31.891748][00:00:19.579668][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:31.891748][00:00:19.579691][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:31.891748][00:00:19.579731][RIGCTRL:debug] icom_set_split_vfo: vfo=Main curr_vfo=Main rx_vfo=Main tx_vfo=Sub split=1
[2021-04-14 23:42:31.891748][00:00:19.579754][RIGCTRL:debug] icom.c(5345):icom_set_split_vfo return(0)
[2021-04-14 23:42:31.891748][00:00:19.579779][RIGCTRL:debug] rig.c(4256):rig_set_split_vfo return(0)
[2021-04-14 23:42:31.891748][00:00:19.579797][RIGCTRL:trace] rig_set_split_freq_mode freq=7074500 mode = USB
[2021-04-14 23:42:31.891748][00:00:19.579827][RIGCTRL:debug] rig.c(4071):rig_set_split_freq_mode entered
[2021-04-14 23:42:31.891748][00:00:19.579860][RIGCTRL:debug] rig_set_split_freq_mode: vfo=VFOB, tx_freq=7074500, tx_mode=USB, tx_width=-1
[2021-04-14 23:42:31.891748][00:00:19.579894][RIGCTRL:debug] icom_set_split_freq_mode called vfo=VFOB
[2021-04-14 23:42:31.891748][00:00:19.579908][RIGCTRL:debug] icom_set_split_freq_mode: curr_vfo=Main
[2021-04-14 23:42:31.891748][00:00:19.579921][RIGCTRL:debug] icom_set_split_freq_mode: before get_split_vfos rx_vfo=Main tx_vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.579935][RIGCTRL:debug] icom_get_split_vfos called
[2021-04-14 23:42:31.891748][00:00:19.579948][RIGCTRL:trace] icom_get_split_vfos: VFO_HAS_MAIN_SUB_ONLY, split=1, rx=Main, tx=Sub
[2021-04-14 23:42:31.891748][00:00:19.579960][RIGCTRL:debug] icom.c(4201):icom_get_split_vfos return(0)
[2021-04-14 23:42:31.891748][00:00:19.579971][RIGCTRL:debug] icom_set_split_freq_mode: after get_split_vfos rx_vfo=Main tx_vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.579982][RIGCTRL:debug] rig.c(2482):rig_set_vfo entered
[2021-04-14 23:42:31.891748][00:00:19.579993][RIGCTRL:debug] rig_set_vfo called vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.580004][RIGCTRL:trace] vfo_fixup: vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.580015][RIGCTRL:debug] rig.c(4326):rig_get_split_vfo entered
[2021-04-14 23:42:31.891748][00:00:19.580026][RIGCTRL:debug] rig.c(4342):rig_get_split_vfo return(-11)
[2021-04-14 23:42:31.891748][00:00:19.580039][RIGCTRL:trace] vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
[2021-04-14 23:42:31.891748][00:00:19.580049][RIGCTRL:trace] vfo_fixup: final vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.580060][RIGCTRL:debug] icom_set_vfo called vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.580071][RIGCTRL:trace] icom_set_vfo: VFO changing from Main to Sub
[2021-04-14 23:42:31.891748][00:00:19.580085][RIGCTRL:trace] icom_set_vfo: line#2295
[2021-04-14 23:42:31.891748][00:00:19.580100][RIGCTRL:trace] icom_set_vfo: Sub asked for, ended up with vfo=Sub
[2021-04-14 23:42:31.891748][00:00:19.580111][RIGCTRL:trace] icom_set_vfo: line#2448
[2021-04-14 23:42:31.891748][00:00:19.580122][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:31.891748][00:00:19.580135][RIGCTRL:debug] icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
[2021-04-14 23:42:31.891748][00:00:19.580146][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:31.891748][00:00:19.580158][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:31.891748][00:00:19.580169][RIGCTRL:debug] frame.c(90):make_cmd_frame return(7)
[2021-04-14 23:42:31.891748][00:00:19.580179][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:31.891748][00:00:19.580190][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:31.891748][00:00:19.580199][RIGCTRL:debug] tcflush
[2021-04-14 23:42:31.891748][00:00:19.580234][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:31.891748][00:00:19.580249][RIGCTRL:debug] write_block called
[2021-04-14 23:42:31.892499][00:00:19.580413][RIGCTRL:trace] write_block(): TX 7 bytes
[2021-04-14 23:42:31.892499][00:00:19.580439][RIGCTRL:trace] 0000 fe fe 50 e0 07 d1 fd ..P....
[2021-04-14 23:42:31.892499][00:00:19.580458][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.892499][00:00:19.580475][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:31.913515][00:00:19.601668][RIGCTRL:trace] read_string(): RX 7 characters
[2021-04-14 23:42:31.913515][00:00:19.601703][RIGCTRL:trace] 0000 fe fe 50 e0 07 d1 fd ..P....
[2021-04-14 23:42:31.913515][00:00:19.601719][RIGCTRL:debug] frame.c(409):read_icom_frame return(7)
[2021-04-14 23:42:31.913515][00:00:19.601732][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.913515][00:00:19.601743][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:31.924536][00:00:19.612617][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:31.924536][00:00:19.612648][RIGCTRL:trace] 0000 fe fe e0 50 fb fd ...P..
[2021-04-14 23:42:31.924536][00:00:19.612663][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:31.924536][00:00:19.612678][RIGCTRL:trace] icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
[2021-04-14 23:42:31.924536][00:00:19.612691][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:31.924536][00:00:19.612702][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:31.924536][00:00:19.612713][RIGCTRL:trace] icom_set_vfo: line#2451
[2021-04-14 23:42:31.924536][00:00:19.612724][RIGCTRL:trace] icom_set_vfo: line#2474 curr_vfo=Sub
[2021-04-14 23:42:31.924536][00:00:19.612735][RIGCTRL:debug] icom.c(2475):icom_set_vfo return(0)
[2021-04-14 23:42:31.924536][00:00:19.612746][RIGCTRL:trace] rig_set_vfo: rig->state.current_vfo=Sub
[2021-04-14 23:42:31.924536][00:00:19.612758][RIGCTRL:debug] icom_get_freq called for Sub, curr_vfo=Sub
[2021-04-14 23:42:31.924536][00:00:19.612768][RIGCTRL:debug] icom_get_freq: using vfo=Sub
[2021-04-14 23:42:31.924536][00:00:19.612779][RIGCTRL:trace] set_vfo_curr: vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:31.924536][00:00:19.612789][RIGCTRL:trace] set_vfo_curr: curr_vfo now=Sub
[2021-04-14 23:42:31.924536][00:00:19.612800][RIGCTRL:debug] icom.c(7828):set_vfo_curr return(0)
[2021-04-14 23:42:31.924536][00:00:19.612811][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:31.924536][00:00:19.612828][RIGCTRL:debug] icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
[2021-04-14 23:42:31.924536][00:00:19.612839][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:31.924536][00:00:19.612850][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:31.924536][00:00:19.612861][RIGCTRL:debug] frame.c(90):make_cmd_frame return(6)
[2021-04-14 23:42:31.924536][00:00:19.612871][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:31.924536][00:00:19.612882][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:31.924536][00:00:19.612892][RIGCTRL:debug] tcflush
[2021-04-14 23:42:31.924536][00:00:19.612922][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:31.924536][00:00:19.612933][RIGCTRL:debug] write_block called
[2021-04-14 23:42:31.924536][00:00:19.613023][RIGCTRL:trace] write_block(): TX 6 bytes
[2021-04-14 23:42:31.924536][00:00:19.613043][RIGCTRL:trace] 0000 fe fe 50 e0 03 fd ..P...
[2021-04-14 23:42:31.924536][00:00:19.613058][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.924536][00:00:19.613069][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:31.946300][00:00:19.634381][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:31.946300][00:00:19.634414][RIGCTRL:trace] 0000 fe fe 50 e0 03 fd ..P...
[2021-04-14 23:42:31.946300][00:00:19.634429][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:31.946300][00:00:19.634443][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.946300][00:00:19.634454][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:31.956809][00:00:19.645119][RIGCTRL:trace] read_string(): RX 11 characters
[2021-04-14 23:42:31.956809][00:00:19.645160][RIGCTRL:trace] 0000 fe fe e0 50 03 00 45 07 07 00 fd ...P..E....
[2021-04-14 23:42:31.956809][00:00:19.645184][RIGCTRL:debug] frame.c(409):read_icom_frame return(11)
[2021-04-14 23:42:31.956809][00:00:19.645208][RIGCTRL:trace] icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
[2021-04-14 23:42:31.956809][00:00:19.645228][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:31.956809][00:00:19.645247][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:31.956809][00:00:19.645267][RIGCTRL:trace] set_vfo_curr: vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:31.956809][00:00:19.645285][RIGCTRL:trace] set_vfo_curr: curr_vfo now=Sub
[2021-04-14 23:42:31.956809][00:00:19.645304][RIGCTRL:debug] icom.c(7828):set_vfo_curr return(0)
[2021-04-14 23:42:31.957562][00:00:19.645346][RIGCTRL:debug] from_bcd called
[2021-04-14 23:42:31.957562][00:00:19.645376][RIGCTRL:debug] icom_get_freq exit vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:31.957562][00:00:19.645397][RIGCTRL:debug] icom.c(1388):icom_get_freq return(0)
[2021-04-14 23:42:31.957562][00:00:19.645428][RIGCTRL:trace] rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1388):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(1388):icom_get_freq return(0)
icom.c(1388):icom_get_freq return(0)
[2021-04-14 23:42:31.957562][00:00:19.645455][RIGCTRL:trace] rig_set_vfo: return 0, vfo=Sub
[2021-04-14 23:42:31.957562][00:00:19.645475][RIGCTRL:debug] rig.c(2568):rig_set_vfo return(0)
[2021-04-14 23:42:31.957562][00:00:19.645504][RIGCTRL:debug] rig_set_freq called vfo=currVFO, freq=7074500
[2021-04-14 23:42:31.957562][00:00:19.645524][RIGCTRL:trace] vfo_fixup: vfo=currVFO
[2021-04-14 23:42:31.957562][00:00:19.645542][RIGCTRL:trace] vfo_fixup: Leaving currVFO alone
[2021-04-14 23:42:31.957562][00:00:19.645560][RIGCTRL:trace] rig_set_freq: TARGETABLE_FREQ vfo=currVFO
[2021-04-14 23:42:31.957562][00:00:19.645585][RIGCTRL:debug] icom_set_freq called currVFO=7074500.000000
[2021-04-14 23:42:31.957562][00:00:19.645605][RIGCTRL:trace] icom_set_freq: currVFO asked for so vfo set to Sub
[2021-04-14 23:42:31.957562][00:00:19.645622][RIGCTRL:trace] icom_set_freq: set_vfo_curr=Sub
[2021-04-14 23:42:31.957562][00:00:19.645639][RIGCTRL:trace] set_vfo_curr: vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:31.957562][00:00:19.645656][RIGCTRL:trace] set_vfo_curr: curr_vfo now=Sub
[2021-04-14 23:42:31.957562][00:00:19.645675][RIGCTRL:debug] icom.c(7828):set_vfo_curr return(0)
[2021-04-14 23:42:31.957562][00:00:19.645694][RIGCTRL:debug] rig_get_freq called vfo=currVFO
[2021-04-14 23:42:31.957562][00:00:19.645730][RIGCTRL:trace] vfo_fixup: vfo=currVFO
[2021-04-14 23:42:31.957562][00:00:19.645748][RIGCTRL:trace] vfo_fixup: Leaving currVFO alone
[2021-04-14 23:42:31.957562][00:00:19.645783][RIGCTRL:debug] rig.c(1512):rig_get_cache entered
[2021-04-14 23:42:31.957562][00:00:19.645803][RIGCTRL:trace] rig_get_cache: vfo=currVFO, current_vfo=Sub
[2021-04-14 23:42:31.957562][00:00:19.645847][RIGCTRL:trace] rig_get_cache: vfo=Sub, freq=0
[2021-04-14 23:42:31.957562][00:00:19.645878][RIGCTRL:debug] rig.c(1621):rig_get_cache return(0)
[2021-04-14 23:42:31.957562][00:00:19.645897][RIGCTRL:trace] rig_get_freq: cache check1 age=26640ms
[2021-04-14 23:42:31.957562][00:00:19.645935][RIGCTRL:trace] rig_get_freq: cache miss age=26640ms, cached_vfo=currVFO, asked_vfo=currVFO
[2021-04-14 23:42:31.957562][00:00:19.645956][RIGCTRL:debug] icom_get_freq called for currVFO, curr_vfo=Sub
[2021-04-14 23:42:31.957562][00:00:19.645975][RIGCTRL:debug] icom_get_freq: using vfo=currVFO
[2021-04-14 23:42:31.957562][00:00:19.645993][RIGCTRL:trace] set_vfo_curr: vfo=currVFO, curr_vfo=Sub
[2021-04-14 23:42:31.957562][00:00:19.646010][RIGCTRL:trace] set_vfo_curr: Asking for currVFO, currVFO=Sub
[2021-04-14 23:42:31.957562][00:00:19.646029][RIGCTRL:debug] icom.c(7776):set_vfo_curr return(0)
[2021-04-14 23:42:31.957562][00:00:19.646047][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:31.957562][00:00:19.646068][RIGCTRL:debug] icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
[2021-04-14 23:42:31.958309][00:00:19.646090][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:31.958309][00:00:19.646110][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:31.958309][00:00:19.646130][RIGCTRL:debug] frame.c(90):make_cmd_frame return(6)
[2021-04-14 23:42:31.958309][00:00:19.646144][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:31.958309][00:00:19.646155][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:31.958309][00:00:19.646165][RIGCTRL:debug] tcflush
[2021-04-14 23:42:31.958309][00:00:19.646199][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:31.958309][00:00:19.646210][RIGCTRL:debug] write_block called
[2021-04-14 23:42:31.958309][00:00:19.646304][RIGCTRL:trace] write_block(): TX 6 bytes
[2021-04-14 23:42:31.958309][00:00:19.646320][RIGCTRL:trace] 0000 fe fe 50 e0 03 fd ..P...
[2021-04-14 23:42:31.958309][00:00:19.646337][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:31.958309][00:00:19.646356][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.011304][00:00:19.699353][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.011304][00:00:19.699390][RIGCTRL:trace] 0000 fe fe 50 e0 03 fd ..P...
[2021-04-14 23:42:32.011304][00:00:19.699414][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.011304][00:00:19.699434][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.011304][00:00:19.699453][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.021813][00:00:19.710007][RIGCTRL:trace] read_string(): RX 11 characters
[2021-04-14 23:42:32.021813][00:00:19.710044][RIGCTRL:trace] 0000 fe fe e0 50 03 00 45 07 07 00 fd ...P..E....
[2021-04-14 23:42:32.021813][00:00:19.710059][RIGCTRL:debug] frame.c(409):read_icom_frame return(11)
[2021-04-14 23:42:32.021813][00:00:19.710076][RIGCTRL:trace] icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
[2021-04-14 23:42:32.021813][00:00:19.710088][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.021813][00:00:19.710099][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.021813][00:00:19.710113][RIGCTRL:trace] set_vfo_curr: vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:32.021813][00:00:19.710124][RIGCTRL:trace] set_vfo_curr: curr_vfo now=Sub
[2021-04-14 23:42:32.021813][00:00:19.710136][RIGCTRL:debug] icom.c(7828):set_vfo_curr return(0)
[2021-04-14 23:42:32.021813][00:00:19.710146][RIGCTRL:debug] from_bcd called
[2021-04-14 23:42:32.021813][00:00:19.710162][RIGCTRL:debug] icom_get_freq exit vfo=currVFO, curr_vfo=Sub
[2021-04-14 23:42:32.021813][00:00:19.710173][RIGCTRL:debug] icom.c(1388):icom_get_freq return(0)
[2021-04-14 23:42:32.021813][00:00:19.710199][RIGCTRL:debug] rig.c(1416):set_cache_freq entered
[2021-04-14 23:42:32.021813][00:00:19.710210][RIGCTRL:trace] set_cache_freq: vfo=currVFO, current_vfo=Sub
[2021-04-14 23:42:32.021813][00:00:19.710236][RIGCTRL:trace] set_cache_freq: set vfo=Sub to freq=7074500
[2021-04-14 23:42:32.021813][00:00:19.710259][RIGCTRL:debug] rig.c(1506):set_cache_freq return(0)
[2021-04-14 23:42:32.021813][00:00:19.710293][RIGCTRL:debug] rig.c(1416):set_cache_freq entered
[2021-04-14 23:42:32.021813][00:00:19.710304][RIGCTRL:trace] set_cache_freq: vfo=currVFO, current_vfo=Sub
[2021-04-14 23:42:32.022563][00:00:19.710338][RIGCTRL:trace] set_cache_freq: set vfo=Sub to freq=7074500
[2021-04-14 23:42:32.022563][00:00:19.710362][RIGCTRL:debug] rig.c(1506):set_cache_freq return(0)
[2021-04-14 23:42:32.022563][00:00:19.710382][RIGCTRL:debug] rig.c(2065):rig_get_freq return(0)
[2021-04-14 23:42:32.022563][00:00:19.710393][RIGCTRL:debug] to_bcd called
[2021-04-14 23:42:32.022563][00:00:19.710407][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:32.022563][00:00:19.710421][RIGCTRL:debug] icom_transaction: cmd=0x05, subcmd=0xffffffff, payload_len=5
[2021-04-14 23:42:32.022563][00:00:19.710432][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:32.022563][00:00:19.710443][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:32.022563][00:00:19.710454][RIGCTRL:debug] frame.c(90):make_cmd_frame return(11)
[2021-04-14 23:42:32.022563][00:00:19.710465][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:32.022563][00:00:19.710476][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:32.022563][00:00:19.710487][RIGCTRL:debug] tcflush
[2021-04-14 23:42:32.022563][00:00:19.710522][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:32.022563][00:00:19.710533][RIGCTRL:debug] write_block called
[2021-04-14 23:42:32.022563][00:00:19.710655][RIGCTRL:trace] write_block(): TX 11 bytes
[2021-04-14 23:42:32.022563][00:00:19.710677][RIGCTRL:trace] 0000 fe fe 50 e0 05 00 45 07 07 00 fd ..P...E....
[2021-04-14 23:42:32.022563][00:00:19.710690][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.022563][00:00:19.710702][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.043580][00:00:19.731877][RIGCTRL:trace] read_string(): RX 11 characters
[2021-04-14 23:42:32.043580][00:00:19.731935][RIGCTRL:trace] 0000 fe fe 50 e0 05 00 45 07 07 00 fd ..P...E....
[2021-04-14 23:42:32.043580][00:00:19.731955][RIGCTRL:debug] frame.c(409):read_icom_frame return(11)
[2021-04-14 23:42:32.043580][00:00:19.731972][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.043580][00:00:19.731985][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.054833][00:00:19.742821][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.054833][00:00:19.742856][RIGCTRL:trace] 0000 fe fe e0 50 fb fd ...P..
[2021-04-14 23:42:32.054833][00:00:19.742872][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.054833][00:00:19.742888][RIGCTRL:trace] icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
[2021-04-14 23:42:32.054833][00:00:19.742900][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.054833][00:00:19.742912][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.105713][00:00:19.793517][RIGCTRL:debug] icom.c(1133):icom_set_freq return(0)
[2021-04-14 23:42:32.105713][00:00:19.793561][RIGCTRL:debug] rig.c(1416):set_cache_freq entered
[2021-04-14 23:42:32.105713][00:00:19.793584][RIGCTRL:trace] set_cache_freq: vfo=currVFO, current_vfo=Sub
[2021-04-14 23:42:32.105713][00:00:19.793627][RIGCTRL:trace] set_cache_freq: set vfo=Sub to freq=0
[2021-04-14 23:42:32.105713][00:00:19.793663][RIGCTRL:debug] rig.c(1506):set_cache_freq return(0)
[2021-04-14 23:42:32.105713][00:00:19.793686][RIGCTRL:debug] rig.c(1416):set_cache_freq entered
[2021-04-14 23:42:32.105713][00:00:19.793706][RIGCTRL:trace] set_cache_freq: vfo=currVFO, current_vfo=Sub
[2021-04-14 23:42:32.105713][00:00:19.793746][RIGCTRL:trace] set_cache_freq: set vfo=Sub to freq=7074500
[2021-04-14 23:42:32.105713][00:00:19.793785][RIGCTRL:debug] rig.c(1506):set_cache_freq return(0)
[2021-04-14 23:42:32.105713][00:00:19.793808][RIGCTRL:debug] rig.c(1859):rig_set_freq return(0)
[2021-04-14 23:42:32.105713][00:00:19.793847][RIGCTRL:debug] icom_set_mode called vfo=currVFO, mode=USB, width=-1
[2021-04-14 23:42:32.105713][00:00:19.793877][RIGCTRL:debug] frame.c(432):rig2icom_mode entered
[2021-04-14 23:42:32.105713][00:00:19.793898][RIGCTRL:trace] rig2icom_mode: mode=4, width=-1
[2021-04-14 23:42:32.105713][00:00:19.793916][RIGCTRL:trace] rig2icom_mode: width==RIG_PASSBAND_NOCHANGE
[2021-04-14 23:42:32.105713][00:00:19.793935][RIGCTRL:debug] rig.c(2207):rig_get_mode entered
[2021-04-14 23:42:32.105713][00:00:19.793972][RIGCTRL:debug] rig.c(1512):rig_get_cache entered
[2021-04-14 23:42:32.105713][00:00:19.793994][RIGCTRL:trace] rig_get_cache: vfo=currVFO, current_vfo=Sub
[2021-04-14 23:42:32.105713][00:00:19.794011][RIGCTRL:trace] rig_get_cache: vfo=Sub, freq=7074500
[2021-04-14 23:42:32.105713][00:00:19.794022][RIGCTRL:debug] rig.c(1621):rig_get_cache return(0)
[2021-04-14 23:42:32.105713][00:00:19.794033][RIGCTRL:trace] rig_get_mode: currVFO cache check age=0ms
[2021-04-14 23:42:32.105713][00:00:19.794055][RIGCTRL:trace] rig_get_mode: cache miss age mode=26788ms, width=26788ms
[2021-04-14 23:42:32.105713][00:00:19.794066][RIGCTRL:debug] icom_get_mode called vfo=currVFO
[2021-04-14 23:42:32.105713][00:00:19.794077][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:32.105713][00:00:19.794091][RIGCTRL:debug] icom_transaction: cmd=0x04, subcmd=0xffffffff, payload_len=0
[2021-04-14 23:42:32.105713][00:00:19.794102][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:32.105713][00:00:19.794113][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:32.105713][00:00:19.794124][RIGCTRL:debug] frame.c(90):make_cmd_frame return(6)
[2021-04-14 23:42:32.105713][00:00:19.794135][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:32.105713][00:00:19.794146][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:32.105713][00:00:19.794156][RIGCTRL:debug] tcflush
[2021-04-14 23:42:32.106466][00:00:19.794203][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:32.106466][00:00:19.794219][RIGCTRL:debug] write_block called
[2021-04-14 23:42:32.106466][00:00:19.794341][RIGCTRL:trace] write_block(): TX 6 bytes
[2021-04-14 23:42:32.106466][00:00:19.794360][RIGCTRL:trace] 0000 fe fe 50 e0 04 fd ..P...
[2021-04-14 23:42:32.106466][00:00:19.794375][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.106466][00:00:19.794386][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.138235][00:00:19.826265][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.138235][00:00:19.826306][RIGCTRL:trace] 0000 fe fe 50 e0 04 fd ..P...
[2021-04-14 23:42:32.138235][00:00:19.826331][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.138235][00:00:19.826352][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.138235][00:00:19.826373][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.148744][00:00:19.836844][RIGCTRL:trace] read_string(): RX 8 characters
[2021-04-14 23:42:32.148744][00:00:19.836880][RIGCTRL:trace] 0000 fe fe e0 50 04 01 01 fd ...P....
[2021-04-14 23:42:32.148744][00:00:19.836895][RIGCTRL:debug] frame.c(409):read_icom_frame return(8)
[2021-04-14 23:42:32.148744][00:00:19.836913][RIGCTRL:trace] icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=01
[2021-04-14 23:42:32.148744][00:00:19.836926][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.148744][00:00:19.836937][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.148744][00:00:19.836954][RIGCTRL:trace] icom_get_mode: modebuf[0]=0x04, modebuf[1]=0x01, mode_len=2
[2021-04-14 23:42:32.148744][00:00:19.836965][RIGCTRL:debug] frame.c(565):icom2rig_mode entered
[2021-04-14 23:42:32.148744][00:00:19.836976][RIGCTRL:trace] icom2rig_mode: mode=0x01, pd=1
[2021-04-14 23:42:32.148744][00:00:19.836993][RIGCTRL:debug] rig.c(2429):rig_passband_wide entered
[2021-04-14 23:42:32.148744][00:00:19.837008][RIGCTRL:debug] rig.c(2453):rig_passband_wide return(0)
[2021-04-14 23:42:32.148744][00:00:19.837026][RIGCTRL:debug] rig.c(2331):rig_passband_normal entered
[2021-04-14 23:42:32.148744][00:00:19.837038][RIGCTRL:debug] rig_passband_normal: return filter#0, width=2400
[2021-04-14 23:42:32.148744][00:00:19.837049][RIGCTRL:debug] rig.c(2346):rig_passband_normal return(2400)
[2021-04-14 23:42:32.148744][00:00:19.837060][RIGCTRL:debug] rig.c(2482):rig_set_vfo entered
[2021-04-14 23:42:32.148744][00:00:19.837071][RIGCTRL:debug] rig_set_vfo called vfo=VFOB
[2021-04-14 23:42:32.148744][00:00:19.837083][RIGCTRL:error] rig_set_vfo: rig does not have VFOB
[2021-04-14 23:42:32.148744][00:00:19.837109][RIGCTRL:debug] rig.c(2497):rig_set_vfo return(-1)
[2021-04-14 23:42:32.148744][00:00:19.837126][RIGCTRL:debug] icom_get_dsp_flt called, mode=USB
[2021-04-14 23:42:32.148744][00:00:19.837139][RIGCTRL:trace] icom_get_mode(2142): vfosave=currVFO, currvfo=Sub
[2021-04-14 23:42:32.148744][00:00:19.837150][RIGCTRL:debug] rig.c(2482):rig_set_vfo entered
[2021-04-14 23:42:32.148744][00:00:19.837160][RIGCTRL:debug] rig_set_vfo called vfo=VFOA
[2021-04-14 23:42:32.148744][00:00:19.837170][RIGCTRL:error] rig_set_vfo: rig does not have VFOA
[2021-04-14 23:42:32.149493][00:00:19.837196][RIGCTRL:debug] rig.c(2497):rig_set_vfo return(-1)
[2021-04-14 23:42:32.149493][00:00:19.837211][RIGCTRL:trace] icom_get_mode: vfo=currVFO returning mode=USB, width=0
[2021-04-14 23:42:32.149493][00:00:19.837228][RIGCTRL:debug] icom.c(2157):icom_get_mode return(0)
[2021-04-14 23:42:32.149493][00:00:19.837240][RIGCTRL:trace] rig_get_mode: retcode after get_mode=0
[2021-04-14 23:42:32.149493][00:00:19.837264][RIGCTRL:trace] rig_get_mode(2295): debug
[2021-04-14 23:42:32.149493][00:00:19.837285][RIGCTRL:trace] rig_get_mode(2303): debug
[2021-04-14 23:42:32.149493][00:00:19.837300][RIGCTRL:debug] rig.c(2331):rig_passband_normal entered
[2021-04-14 23:42:32.149493][00:00:19.837316][RIGCTRL:debug] rig_passband_normal: return filter#0, width=2400
[2021-04-14 23:42:32.149493][00:00:19.837327][RIGCTRL:debug] rig.c(2346):rig_passband_normal return(2400)
[2021-04-14 23:42:32.149493][00:00:19.837342][RIGCTRL:debug] rig.c(1345):set_cache_mode entered
[2021-04-14 23:42:32.149493][00:00:19.837377][RIGCTRL:debug] rig.c(1409):set_cache_mode return(0)
[2021-04-14 23:42:32.149493][00:00:19.837400][RIGCTRL:debug] rig.c(2310):rig_get_mode return(0)
[2021-04-14 23:42:32.149493][00:00:19.837412][RIGCTRL:debug] frame.c(556):rig2icom_mode return(0)
[2021-04-14 23:42:32.149493][00:00:19.837424][RIGCTRL:debug] icom_set_mode: icmode=1, icmode_ext=-1
[2021-04-14 23:42:32.149493][00:00:19.837435][RIGCTRL:debug] icom_set_mode: #2 icmode=1, icmode_ext=-1
[2021-04-14 23:42:32.149493][00:00:19.837446][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:32.149493][00:00:19.837459][RIGCTRL:debug] icom_transaction: cmd=0x06, subcmd=0x01, payload_len=0
[2021-04-14 23:42:32.149493][00:00:19.837474][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:32.149493][00:00:19.837485][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:32.149493][00:00:19.837496][RIGCTRL:debug] frame.c(90):make_cmd_frame return(7)
[2021-04-14 23:42:32.149493][00:00:19.837506][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:32.149493][00:00:19.837518][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:32.149493][00:00:19.837526][RIGCTRL:debug] tcflush
[2021-04-14 23:42:32.149493][00:00:19.837558][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:32.149493][00:00:19.837568][RIGCTRL:debug] write_block called
[2021-04-14 23:42:32.149493][00:00:19.837673][RIGCTRL:trace] write_block(): TX 7 bytes
[2021-04-14 23:42:32.149493][00:00:19.837696][RIGCTRL:trace] 0000 fe fe 50 e0 06 01 fd ..P....
[2021-04-14 23:42:32.149493][00:00:19.837723][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.149493][00:00:19.837746][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.171257][00:00:19.859234][RIGCTRL:trace] read_string(): RX 7 characters
[2021-04-14 23:42:32.171257][00:00:19.859265][RIGCTRL:trace] 0000 fe fe 50 e0 06 01 fd ..P....
[2021-04-14 23:42:32.171257][00:00:19.859280][RIGCTRL:debug] frame.c(409):read_icom_frame return(7)
[2021-04-14 23:42:32.171257][00:00:19.859292][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.171257][00:00:19.859303][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.181766][00:00:19.869748][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.181766][00:00:19.869778][RIGCTRL:trace] 0000 fe fe e0 50 fb fd ...P..
[2021-04-14 23:42:32.181766][00:00:19.869792][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.181766][00:00:19.869808][RIGCTRL:trace] icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
[2021-04-14 23:42:32.181766][00:00:19.869821][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.181766][00:00:19.869832][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.181766][00:00:19.869846][RIGCTRL:debug] icom.c(1870):icom_set_mode return(0)
[2021-04-14 23:42:32.181766][00:00:19.869859][RIGCTRL:debug] rig.c(2482):rig_set_vfo entered
[2021-04-14 23:42:32.181766][00:00:19.869870][RIGCTRL:debug] rig_set_vfo called vfo=Main
[2021-04-14 23:42:32.181766][00:00:19.869881][RIGCTRL:trace] vfo_fixup: vfo=Main
[2021-04-14 23:42:32.181766][00:00:19.869892][RIGCTRL:debug] rig.c(4326):rig_get_split_vfo entered
[2021-04-14 23:42:32.181766][00:00:19.869903][RIGCTRL:debug] rig.c(4342):rig_get_split_vfo return(-11)
[2021-04-14 23:42:32.181766][00:00:19.869916][RIGCTRL:trace] vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
[2021-04-14 23:42:32.181766][00:00:19.869927][RIGCTRL:trace] vfo_fixup: final vfo=Sub
[2021-04-14 23:42:32.181766][00:00:19.869938][RIGCTRL:debug] icom_set_vfo called vfo=Sub
[2021-04-14 23:42:32.181766][00:00:19.869949][RIGCTRL:trace] icom_set_vfo: line#2295
[2021-04-14 23:42:32.181766][00:00:19.869960][RIGCTRL:trace] icom_set_vfo: Sub asked for, ended up with vfo=Sub
[2021-04-14 23:42:32.181766][00:00:19.869971][RIGCTRL:trace] icom_set_vfo: line#2448
[2021-04-14 23:42:32.181766][00:00:19.869982][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:32.181766][00:00:19.869995][RIGCTRL:debug] icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
[2021-04-14 23:42:32.181766][00:00:19.870006][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:32.181766][00:00:19.870017][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:32.181766][00:00:19.870027][RIGCTRL:debug] frame.c(90):make_cmd_frame return(7)
[2021-04-14 23:42:32.181766][00:00:19.870038][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:32.181766][00:00:19.870049][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:32.181766][00:00:19.870059][RIGCTRL:debug] tcflush
[2021-04-14 23:42:32.181766][00:00:19.870090][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:32.181766][00:00:19.870100][RIGCTRL:debug] write_block called
[2021-04-14 23:42:32.182517][00:00:19.870227][RIGCTRL:trace] write_block(): TX 7 bytes
[2021-04-14 23:42:32.182517][00:00:19.870247][RIGCTRL:trace] 0000 fe fe 50 e0 07 d1 fd ..P....
[2021-04-14 23:42:32.182517][00:00:19.870261][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.182517][00:00:19.870273][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.203531][00:00:19.891526][RIGCTRL:trace] read_string(): RX 7 characters
[2021-04-14 23:42:32.203531][00:00:19.891556][RIGCTRL:trace] 0000 fe fe 50 e0 07 d1 fd ..P....
[2021-04-14 23:42:32.203531][00:00:19.891571][RIGCTRL:debug] frame.c(409):read_icom_frame return(7)
[2021-04-14 23:42:32.203531][00:00:19.891583][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.203531][00:00:19.891594][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.214036][00:00:19.901986][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.214036][00:00:19.902017][RIGCTRL:trace] 0000 fe fe e0 50 fb fd ...P..
[2021-04-14 23:42:32.214036][00:00:19.902031][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.214036][00:00:19.902048][RIGCTRL:trace] icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
[2021-04-14 23:42:32.214036][00:00:19.902060][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.214036][00:00:19.902072][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.214036][00:00:19.902088][RIGCTRL:trace] icom_set_vfo: line#2451
[2021-04-14 23:42:32.214036][00:00:19.902099][RIGCTRL:trace] icom_set_vfo: line#2474 curr_vfo=Sub
[2021-04-14 23:42:32.214036][00:00:19.902113][RIGCTRL:debug] icom.c(2475):icom_set_vfo return(0)
[2021-04-14 23:42:32.214036][00:00:19.902125][RIGCTRL:trace] rig_set_vfo: rig->state.current_vfo=Sub
[2021-04-14 23:42:32.214036][00:00:19.902136][RIGCTRL:debug] icom_get_freq called for Sub, curr_vfo=Sub
[2021-04-14 23:42:32.214036][00:00:19.902146][RIGCTRL:debug] icom_get_freq: using vfo=Sub
[2021-04-14 23:42:32.214036][00:00:19.902158][RIGCTRL:trace] set_vfo_curr: vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:32.214036][00:00:19.902168][RIGCTRL:trace] set_vfo_curr: curr_vfo now=Sub
[2021-04-14 23:42:32.214036][00:00:19.902179][RIGCTRL:debug] icom.c(7828):set_vfo_curr return(0)
[2021-04-14 23:42:32.214036][00:00:19.902190][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:32.214036][00:00:19.902204][RIGCTRL:debug] icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
[2021-04-14 23:42:32.214036][00:00:19.902215][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:32.214036][00:00:19.902227][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:32.214036][00:00:19.902238][RIGCTRL:debug] frame.c(90):make_cmd_frame return(6)
[2021-04-14 23:42:32.214036][00:00:19.902249][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:32.214036][00:00:19.902261][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:32.214036][00:00:19.902270][RIGCTRL:debug] tcflush
[2021-04-14 23:42:32.214036][00:00:19.902302][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:32.214036][00:00:19.902312][RIGCTRL:debug] write_block called
[2021-04-14 23:42:32.214036][00:00:19.902420][RIGCTRL:trace] write_block(): TX 6 bytes
[2021-04-14 23:42:32.214786][00:00:19.902444][RIGCTRL:trace] 0000 fe fe 50 e0 03 fd ..P...
[2021-04-14 23:42:32.214786][00:00:19.902464][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.214786][00:00:19.902475][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.267322][00:00:19.955285][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.267322][00:00:19.955329][RIGCTRL:trace] 0000 fe fe 50 e0 03 fd ..P...
[2021-04-14 23:42:32.267322][00:00:19.955354][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.267322][00:00:19.955376][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.267322][00:00:19.955395][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.277831][00:00:19.966044][RIGCTRL:trace] read_string(): RX 11 characters
[2021-04-14 23:42:32.277831][00:00:19.966087][RIGCTRL:trace] 0000 fe fe e0 50 03 00 45 07 07 00 fd ...P..E....
[2021-04-14 23:42:32.277831][00:00:19.966112][RIGCTRL:debug] frame.c(409):read_icom_frame return(11)
[2021-04-14 23:42:32.277831][00:00:19.966136][RIGCTRL:trace] icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=00
[2021-04-14 23:42:32.277831][00:00:19.966157][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.277831][00:00:19.966177][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.277831][00:00:19.966197][RIGCTRL:trace] set_vfo_curr: vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:32.278580][00:00:19.966223][RIGCTRL:trace] set_vfo_curr: curr_vfo now=Sub
[2021-04-14 23:42:32.278580][00:00:19.966243][RIGCTRL:debug] icom.c(7828):set_vfo_curr return(0)
[2021-04-14 23:42:32.278580][00:00:19.966261][RIGCTRL:debug] from_bcd called
[2021-04-14 23:42:32.278580][00:00:19.966279][RIGCTRL:debug] icom_get_freq exit vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:32.278580][00:00:19.966300][RIGCTRL:debug] icom.c(1388):icom_get_freq return(0)
[2021-04-14 23:42:32.278580][00:00:19.966332][RIGCTRL:trace] rig_set_vfo: retcode from rig_get_freq = Command completed successfully
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1388):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(1388):icom_get_freq return(0)
icom.c(1388):icom_get_freq return(0)
[2021-04-14 23:42:32.278580][00:00:19.966355][RIGCTRL:trace] rig_set_vfo: return 0, vfo=Sub
[2021-04-14 23:42:32.278580][00:00:19.966373][RIGCTRL:debug] rig.c(2568):rig_set_vfo return(0)
[2021-04-14 23:42:32.278580][00:00:19.966406][RIGCTRL:debug] icom.c(4996):icom_set_split_freq_mode return(0)
[2021-04-14 23:42:32.278580][00:00:19.966425][RIGCTRL:debug] rig.c(4122):rig_set_split_freq_mode return(0)
[2021-04-14 23:42:32.278580][00:00:19.966434][RIGCTRL:trace] rig_set_split_vfo split=1
[2021-04-14 23:42:32.278580][00:00:19.966461][RIGCTRL:debug] rig.c(4227):rig_set_split_vfo entered
[2021-04-14 23:42:32.278580][00:00:19.966478][RIGCTRL:trace] vfo_fixup: vfo=currVFO
[2021-04-14 23:42:32.278580][00:00:19.966494][RIGCTRL:trace] vfo_fixup: Leaving currVFO alone
[2021-04-14 23:42:32.278580][00:00:19.966515][RIGCTRL:debug] icom_set_split_vfo called vfo='currVFO', split=1, tx_vfo=Sub, curr_vfo=Sub
[2021-04-14 23:42:32.278580][00:00:19.966532][RIGCTRL:trace] icom_set_split_vfo: vfo clause 4
[2021-04-14 23:42:32.278580][00:00:19.966549][RIGCTRL:trace] icom_set_split_vfo: set_vfo because tx_vfo=Sub
[2021-04-14 23:42:32.278580][00:00:19.966571][RIGCTRL:debug] frame.c(325):icom_transaction entered
[2021-04-14 23:42:32.278580][00:00:19.966591][RIGCTRL:debug] icom_transaction: cmd=0x0f, subcmd=0x01, payload_len=0
[2021-04-14 23:42:32.278580][00:00:19.966608][RIGCTRL:debug] frame.c(119):icom_one_transaction entered
[2021-04-14 23:42:32.278580][00:00:19.966626][RIGCTRL:debug] frame.c(56):make_cmd_frame entered
[2021-04-14 23:42:32.278580][00:00:19.966645][RIGCTRL:debug] frame.c(90):make_cmd_frame return(7)
[2021-04-14 23:42:32.278580][00:00:19.966662][RIGCTRL:trace] rig_flush: called for serial device
[2021-04-14 23:42:32.278580][00:00:19.966680][RIGCTRL:debug] serial.c(629):serial_flush entered
[2021-04-14 23:42:32.278580][00:00:19.966694][RIGCTRL:debug] tcflush
[2021-04-14 23:42:32.278580][00:00:19.966744][RIGCTRL:debug] serial.c(661):serial_flush return(0)
[2021-04-14 23:42:32.278580][00:00:19.966762][RIGCTRL:debug] write_block called
[2021-04-14 23:42:32.278580][00:00:19.966882][RIGCTRL:trace] write_block(): TX 7 bytes
[2021-04-14 23:42:32.278580][00:00:19.966911][RIGCTRL:trace] 0000 fe fe 50 e0 0f 01 fd ..P....
[2021-04-14 23:42:32.278580][00:00:19.966934][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.279330][00:00:19.967006][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.289838][00:00:19.977815][RIGCTRL:trace] read_string(): RX 7 characters
[2021-04-14 23:42:32.289838][00:00:19.977857][RIGCTRL:trace] 0000 fe fe 50 e0 0f 01 fd ..P....
[2021-04-14 23:42:32.289838][00:00:19.977881][RIGCTRL:debug] frame.c(409):read_icom_frame return(7)
[2021-04-14 23:42:32.289838][00:00:19.977901][RIGCTRL:debug] frame.c(375):read_icom_frame entered
[2021-04-14 23:42:32.289838][00:00:19.977919][RIGCTRL:trace] read_string called, rxmax=80
[2021-04-14 23:42:32.311088][00:00:19.999002][RIGCTRL:trace] read_string(): RX 6 characters
[2021-04-14 23:42:32.311088][00:00:19.999033][RIGCTRL:trace] 0000 fe fe e0 50 fb fd ...P..
[2021-04-14 23:42:32.311088][00:00:19.999048][RIGCTRL:debug] frame.c(409):read_icom_frame return(6)
[2021-04-14 23:42:32.311088][00:00:19.999064][RIGCTRL:trace] icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
[2021-04-14 23:42:32.311088][00:00:19.999076][RIGCTRL:debug] frame.c(303):icom_one_transaction return(0)
[2021-04-14 23:42:32.311088][00:00:19.999087][RIGCTRL:debug] frame.c(355):icom_transaction return(0)
[2021-04-14 23:42:32.311088][00:00:19.999104][RIGCTRL:debug] icom_set_split_vfo: vfo=Sub curr_vfo=Sub rx_vfo=Sub tx_vfo=Sub split=1
[2021-04-14 23:42:32.311088][00:00:19.999115][RIGCTRL:debug] icom.c(5345):icom_set_split_vfo return(0)
[2021-04-14 23:42:32.311088][00:00:19.999127][RIGCTRL:debug] rig.c(4256):rig_set_split_vfo return(0)
[2021-04-14 23:42:32.311088][00:00:19.999231][SYSLOG:trace] #: 3 Transceiver::TransceiverState(online: yes Frequency {7074000Hz, 7074500Hz} Mode: 3; SPLIT: on; PTT: off)
The text was updated successfully, but these errors were encountered: