ID-5100 Dual Watch mode

[mdblack98]

Concerning gpredict... I see that when gpredict is setup for Duplex TRX, Read PTT and Main ^ / Sub v, then gpredict sends a S command to rigctrld to set the Main VFO for TX. Then it uses the I and i commands for setting and getting the TX frequency. It uses the F and f commands for setting and getting the RX frequency. It also uses the t command to get the PTT status.

What do you think about getting Hamlib, in the case of the ID-5100, to:

• Assume the A band (left side of the rig) is the uplink (Main) and the B band (right side of the rig) is the downlink (Sub);

• Upon receiving the S command, set the rig's VFO mode (cmd 07) so that the A band is the Main.

• When an I or i command is received, and if PTT is not active, change the rig's VFO mode so that A band is Main (In case the user changed it on the rig's panel), set or get the frequency.

• When a F or f command is received, and if PTT is not active, change the rig's VFO mode so that B band is Main, set or get the frequency, and change the rig's VFO mode so that A band is Main.
  The prerequisites would be:

• The rig is in Dualwatch mode with Main on the left side.

• Both sides (A and B bands) must be set to VFO mode (not memory mode).

• The ID5100 rig interface in gpredict must be set to Duplex TRX, Read PTT and Main ^/Sub v.

[mdblack98]

downloaded your fork of gpredict. I had to fix a syntax error on lines 25 and 26 in gpredict.rc in the ...\win32 directory. I replaced the use of VERSION_RSRC with 3,3,0,0.

I ran it with the daily snapshot of hamlib.

To me, it looks like hamlib, for the ID-5100, has Main and Sub reversed. It is trying to use Main as the RX vfo and Sub as the TX vfo. On the ID-5100, Main is always the TX vfo. Attached is a log file from rigctld. Main on the rig is displaying the downlink frequency on vfo A. I can see the quickly changing the Main to vfo B, but the frequency is not changing.
To start rigctld I am using the following parameters:

-m 3071 -r COM6 -s 9600 -c 0x8C -T 127.0.0.1 -t 4534 -vvvvv

Following shows the settings for the Radio Interface in gpredict.
rig_init: rig_model=Icom ID-5100
rig_init: rig has VFO_SUB
rig_init: rig has VFO_MAIN
1:icom.c(623):icom_init entered
icom_init: done
1:icom.c(724):icom_init returning(0)
main: twiddle=0, uplink=0, twiddle_rit=0
rig_token_lookup called for civaddr rig_init: rig_model=Icom ID-5100
rig_init: rig has VFO_SUB
rig_init: rig has VFO_MAIN
1:icom.c(623):icom_init entered
icom_init: done
1:icom.c(724):icom_init returning(0)
main: twiddle=0, uplink=0, twiddle_rit=0
rig_token_lookup called for civaddr
rig_confparam_lookup called for civaddr
rig_set_conf called
rig_confparam_lookup called for 1
rig_set_conf: civaddr='0x8C'
1:icom.c(4958):icom_set_conf entered
1:icom.c(4988):icom_set_conf returning(0)
1:rig.c(819):rig_open entered
rig_settings_get_path: path=.hamlib_settings
rig_settings_load_all: settings_file (.hamlib_settings): No such file or directory
rig_open: cwd=C:\Users\leeot\OneDrive\Documents\CodeProjects\hamlib-4.6git\tests
rig_open: C:\Users\leeot\OneDrive\Documents\CodeProjects\hamlib-4.6git\tests/hamlib_settings does not exist
rig_open: async_data_enable=0, async_data_supported=0
serial_open: COM6
2:rig.c(7452):async_data_handler_start entered
async_data_handler_start: async data support disabled since async_data_enabled=0
2:rig.c(7459):async_data_handler_start returning(0)
rig.c(254):add_opened_rig returning2(0)
rig_open: 000001d0c92a98bc rs->comm_state==1?=1
2:icom.c(987):icom_rig_open entered
icom_rig_open: ID-5100 v20230118.4
3:icom.c(777):icom_get_usb_echo_off entered
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=03
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_get_usb_echo_off: ack_len=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_get_usb_echo_off: USB echo on detected, get freq retval=11
3:icom.c(806):icom_get_usb_echo_off returning(0)
icom_rig_open: echo status result=0
icom_rig_open: echo status known, getting frequency
icom_current_vfo: defaulting to VFOA as no XCHG or x25 available
3:rig.c(2733):rig_set_vfo entered
rig_set_vfo called vfo=VFOA
vfo_fixup:(from rig_set_vfo:2753) vfo=VFOA, vfo_curr=None, split=0
vfo_fixup:(from rig_set_vfo:2795) vfo=Main, vfo_curr=None, split=0
rig.c(2814) trace
icom_set_vfo called vfo=Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_set_vfo: rig->state.current_vfo=Main
rig.c(2840) trace
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
rig_set_vfo: retcode from rig_get_freq = 0
rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
3:rig_set_vfo: elapsed=110ms
3:rig.c(2865):rig_set_vfo returning(0)
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=VFOA, split=0
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=VFOA
rig_get_freq: VFOA cache hit age=0ms, freq=437800000, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
icom_current_vfo: defaulting to VFOA as no XCHG or x25 available
3:rig.c(2733):rig_set_vfo entered
rig_set_vfo called vfo=VFOA
vfo_fixup:(from rig_set_vfo:2753) vfo=VFOA, vfo_curr=VFOA, split=0
vfo_fixup:(from rig_set_vfo:2795) vfo=Main, vfo_curr=VFOA, split=0
rig.c(2814) trace
icom_set_vfo called vfo=Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_set_vfo: rig->state.current_vfo=Main
rig.c(2840) trace
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
rig_set_vfo: retcode from rig_get_freq = 0
rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
3:rig_set_vfo: elapsed=109ms
3:rig.c(2865):rig_set_vfo returning(0)
2:icom.c(1127):icom_rig_open returning(0)
rig.c(1286) trace
rig_open: vfo_curr=VFOA, tx_vfo=TX
rig_open: default vfo = VFOA
rig_get_freq(1995) called vfo=VFOA
vfo_fixup:(from rig_get_freq:2002) vfo=VFOA, vfo_curr=VFOA, split=0
rig.c(2004) vfo=Main, curr_vfo=VFOA
rig_get_freq: Main cache hit age=0ms, freq=437800000, use_cached_freq=0
1:rig_get_freq: elapsed=0ms
rig_get_freq(1995) called vfo=VFOB
vfo_fixup:(from rig_get_freq:2002) vfo=VFOB, vfo_curr=VFOA, split=0
vfo_fixup: final vfo=Sub
rig.c(2004) vfo=Sub, curr_vfo=VFOA
rig_get_freq: cache miss age=1000000ms, cached_vfo=Sub, asked_vfo=Sub, use_cached_freq=0
rig_get_freq(2091): vfo_opt=0, model=3071
rig.c(2135) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from VFOA to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
2:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
3:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
3:frame.c(403):icom_one_transaction returning(0)
2:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(2146) trace
icom_get_freq called for Sub, curr_vfo=Sub
icom_get_freq: using vfo=Sub
icom.c(1671) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
2:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
3:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
3:frame.c(403):icom_one_transaction returning(0)
2:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Sub, curr_vfo=Sub
icom.c(1783):icom_get_freq returning2(0)
icom_set_vfo called vfo=VFOA
icom_set_vfo: Rig does not have VFO A/B?
icom_set_vfo: Mapping VFOA=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
2:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
3:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
3:frame.c(403):icom_one_transaction returning(0)
2:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
1:rig_get_freq: elapsed=144ms
2:rig.c(5094):rig_get_split_vfo entered
rig_get_split_vfo: no get_split_vfo so returning split=0, tx_vfo=None
2:rig_get_split_vfo: elapsed=0ms
2:rig.c(5121):rig_get_split_vfo returning(0)
rig_open(1357): Current split=0, tx_vfo=None
2:rig.c(2415):rig_get_mode entered
vfo_fixup:(from rig_get_mode:2438) vfo=VFOA, vfo_curr=Main, split=0
rig_get_mode: Main cache check age=254ms
rig_get_mode: cache miss age mode=254ms, width=254ms
rig.c(2486) trace
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x04, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 04 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 04 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x04
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 8 characters, direct=1
0000 fe fe e0 8c 04 05 01 fd ........
icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=01
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
rig_get_mode: retcode after get_mode=0
rig_get_mode(2532): debug
3:cache.c(35):rig_set_cache_mode entered
3:cache.c(108):rig_set_cache_mode returning(0)
2:rig_get_mode: elapsed=47ms
2:rig.c(2548):rig_get_mode returning(0)
1:rig.c(1381):rig_open returning(0)
Opened rig model 3071, 'ID-5100'
Backend version: 20230118.4, Status: Stable
main: Using IPV4
1:network.c(977):network_multicast_publisher_start entered
network.c(979):multicast address=0.0.0.0, port=4532
network.c(985): not starting multicast publisher
main: rigctld listening on port 4534
Connection opened from 127.0.0.1:57339
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=0, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=(5c) handle=3
rigctl_parse: cmd=set_vfo_opt handle=3
mutex_rigctld: client lock engaged
rigctl(d): ≥ 'currVFO' '1' '' ''
rigctl_parse: vfo_opt=0
2:rigctl_parse.c(5048):rigctl_set_vfo_opt entered
3:rig.c(6911):rig_set_vfo_opt entered
3:rig_set_vfo_opt: elapsed=0ms
3:rig.c(6927):rig_set_vfo_opt returning(0)
2:rigctl_parse.c(5052):rigctl_set_vfo_opt returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=(5c) handle=3
rigctl_parse: cmd=chk_vfo handle=3
mutex_rigctld: client lock engaged
rigctl(d): ≡ 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(5030):rigctl_chk_vfo entered
2:rigctl_parse.c(5041):rigctl_chk_vfo returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=S(53) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): S 'Sub' '1' 'Main' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(3064):rigctl_set_split_vfo entered
rig_parse_vfo called
rigctl_set_split_vfo(3083): rx_vfo = Sub, tx_vfo = Main
3:rig.c(4921):rig_set_split_vfo entered
rig_set_split_vfo: rx_vfo=Sub, split=1, tx_vfo=Main, cache.split=0
vfo_fixup:(from rig_set_split_vfo:4966) vfo=VFOB, vfo_curr=Main, split=1
vfo_fixup: final vfo=Sub
vfo_fixup:(from rig_set_split_vfo:4967) vfo=Main, vfo_curr=Main, split=1
rig_set_split_vfo: final rxvfo=Sub, txvfo=Main, split=0
rig.c(4976) trace
4:rig.c(2733):rig_set_vfo entered
rig_set_vfo called vfo=VFOA
vfo_fixup:(from rig_set_vfo:2753) vfo=VFOA, vfo_curr=Main, split=0
vfo_fixup:(from rig_set_vfo:2795) vfo=Main, vfo_curr=Main, split=0
rig.c(2814) trace
icom_set_vfo called vfo=Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
5:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
6:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
6:frame.c(403):icom_one_transaction returning(0)
5:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_set_vfo: rig->state.current_vfo=Main
rig.c(2840) trace
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
5:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
6:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
6:frame.c(403):icom_one_transaction returning(0)
5:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
rig_set_vfo: retcode from rig_get_freq = 0
rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
4:rig_set_vfo: elapsed=122ms
4:rig.c(2865):rig_set_vfo returning(0)
rig.c(5021) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from Main to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(5034) trace
icom_set_split_vfo called vfo='Sub', split=1, tx_vfo=Main, curr_vfo=Sub
trace icom_set_split_vfo(6517)
icom_set_split_vfo: vfo clause 4
icom_set_split_vfo: set_vfo because tx_vfo=Main
4:icom.c(6931):icom_set_func entered
5:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x16, subcmd=0x59, payload_len=1
6:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 8 bytes, method=2
0000 fe fe 8c e0 16 59 01 fd .....Y..
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 8 characters, direct=1
0000 fe fe 8c e0 16 59 01 fd .....Y..
icom_one_transaction: DEBUG retval=8, frm_len=8, cmd=0x16
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
6:frame.c(403):icom_one_transaction returning(0)
5:frame.c(455):icom_transaction returning(0)
4:icom.c(7210):icom_set_func returning(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_split_vfo: vfo=Sub curr_vfo=Sub rx_vfo=Sub tx_vfo=Main split=1
icom.c(6685):icom_set_split_vfo returning2(0)
icom_set_vfo called vfo=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
3:rig_set_split_vfo: elapsed=334ms
3:rig.c(5058):rig_set_split_vfo returning(0)
2:rigctl_parse.c(3086):rigctl_set_split_vfo returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=t(74) handle=3
rig_parse_vfo called
rig_parse_vfo: 'vfoCurr' not found so vfo='None'
mutex_rigctld: client lock engaged
rigctl(d): t 'None' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2607):rigctl_get_ptt entered
3:rig.c(3293):rig_get_ptt entered
rig_get_ptt: cache check age=1000000ms
rig_get_ptt: cache miss age=1000000ms
rig.c(3359) trace
rig.c(3383) trace
4:icom.c(5076):icom_get_ptt entered
5:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x1c, subcmd=0x00, payload_len=0
6:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 1c 00 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 1c 00 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x1c
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 8 characters, direct=1
0000 fe fe e0 8c 1c 00 00 fd ........
icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=00
6:frame.c(403):icom_one_transaction returning(0)
5:frame.c(455):icom_transaction returning(0)
4:icom.c(5099):icom_get_ptt returning(0)
3:rig_get_ptt: elapsed=45ms
3:rig.c(3401):rig_get_ptt returning(0)
2:rigctl_parse.c(2624):rigctl_get_ptt returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=F(46) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): F 'currVFO' '437806970' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2104):rigctl_set_freq entered
rig_set_freq called vfo=currVFO, freq=437806970
vfo_fixup:(from rig_set_freq:1785) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig_set_freq: TARGETABLE_FREQ vfo=currVFO
rig.c(1813) trace
icom_set_freq called currVFO=437806970
icom.c(1324) trace
icom_set_freq: set_vfo_curr=Main
set_vfo_curr: vfo=currVFO, curr_vfo=Main
set_vfo_curr: Asking for currVFO, currVFO=Main
icom.c(9428):set_vfo_curr returning2(0)
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: Main cache hit age=259ms, freq=437800000, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
to_bcd called
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x00, subcmd=0xffffffff, payload_len=5
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 11 bytes, method=2
0000 fe fe 8c e0 00 70 69 80 37 04 fd .....pi.7..
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe 8c e0 00 70 69 80 37 04 fd .....pi.7..
icom_one_transaction: DEBUG retval=11, frm_len=11, cmd=0x00
4:frame.c(261):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
rig.c(1923) trace
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: cache miss age=10000ms, cached_vfo=Main, asked_vfo=Main, use_cached_freq=0
rig_get_freq(2091): vfo_opt=1, model=3071
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 50 62 80 37 04 fd .....Pb.7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
2:rig_get_freq: elapsed=49ms
rig_set_freq: Asked freq=437806970, got freq=437806250
2:rig_set_freq: elapsed=79ms
rig.c(1954):rig_set_freq returning2(0)
2:rigctl_parse.c(2116):rigctl_set_freq returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=f(66) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): f 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2128):rigctl_get_freq entered
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: Main cache hit age=12ms, freq=437806250, use_cached_freq=0
2:rig_get_freq: elapsed=1ms
2:rigctl_parse.c(2154):rigctl_get_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=I(49) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): I 'VFOA' '145987675' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2894):rigctl_set_split_freq entered
rig_set_split_freq called vfo=TX, curr_vfo=Main, tx_freq=145987675
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: Main cache hit age=13ms, freq=437806250, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
vfo_fixup:(from rig_set_split_freq:4092) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
rig.c(4105) trace
rig_set_freq called vfo=Main, freq=145987675
vfo_fixup:(from rig_set_freq:1785) vfo=Main, vfo_curr=Main, split=1
rig_set_freq: TARGETABLE_FREQ vfo=Main
rig.c(1813) trace
icom_set_freq called Main=145987675
icom.c(1324) trace
icom_set_freq: set_vfo_curr=Main
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: Main cache hit age=13ms, freq=437806250, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
to_bcd called
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x00, subcmd=0xffffffff, payload_len=5
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 11 bytes, method=2
0000 fe fe 8c e0 00 75 76 98 45 01 fd .....uv.E..
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe 8c e0 00 75 76 98 45 01 fd .....uv.E..
icom_one_transaction: DEBUG retval=11, frm_len=11, cmd=0x00
4:frame.c(261):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
rig.c(1923) trace
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: cache miss age=10000ms, cached_vfo=Main, asked_vfo=Main, use_cached_freq=0
rig_get_freq(2091): vfo_opt=1, model=3071
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
2:rig_get_freq: elapsed=62ms
rig_set_freq: Asked freq=145987675, got freq=145987500
rig.c(1949) trace
3:rig.c(2733):rig_set_vfo entered
rig_set_vfo called vfo=Main
vfo_fixup:(from rig_set_vfo:2753) vfo=Main, vfo_curr=Main, split=1
vfo_fixup:(from rig_set_vfo:2795) vfo=Main, vfo_curr=Main, split=1
rig.c(2814) trace
icom_set_vfo called vfo=Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_set_vfo: rig->state.current_vfo=Main
rig.c(2840) trace
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
rig_set_vfo: retcode from rig_get_freq = 0
rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
3:rig_set_vfo: elapsed=116ms
3:rig.c(2865):rig_set_vfo returning(0)
2:rig_set_freq: elapsed=226ms
rig.c(1954):rig_set_freq returning2(0)
2:rig_set_split_freq: elapsed=227ms
rig.c(4119):rig_set_split_freq returning2(0)
2:rigctl_parse.c(2898):rigctl_set_split_freq returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=i(69) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): i 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2909):rigctl_get_split_freq entered
3:rig.c(4215):rig_get_split_freq entered
vfo_fixup:(from rig_get_split_freq:4229) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
rig.c(4272) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from Main to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(4303) trace
icom_get_freq called for currVFO, curr_vfo=Sub
icom_get_freq: using vfo=currVFO
icom.c(1671) trace
set_vfo_curr: vfo=currVFO, curr_vfo=Sub
set_vfo_curr: Asking for currVFO, currVFO=Sub
icom.c(9428):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=currVFO, curr_vfo=Sub
icom.c(1783):icom_get_freq returning2(0)
rig_get_split_freq: restoring vfo=Main
rig.c(4317) trace
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
icom_set_vfo called vfo=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_get_split_freq: tx_freq=437800000
3:rig_get_split_freq: elapsed=191ms
3:rig.c(4343):rig_get_split_freq returning(0)
2:rigctl_parse.c(2925):rigctl_get_split_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=t(74) handle=3
rig_parse_vfo called
rig_parse_vfo: 'vfoCurr' not found so vfo='None'
mutex_rigctld: client lock engaged
rigctl(d): t 'None' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2607):rigctl_get_ptt entered
3:rig.c(3293):rig_get_ptt entered
rig_get_ptt: cache check age=10375ms
rig_get_ptt: cache miss age=10375ms
rig.c(3359) trace
rig.c(3383) trace
4:icom.c(5076):icom_get_ptt entered
5:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x1c, subcmd=0x00, payload_len=0
6:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 1c 00 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 1c 00 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x1c
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 8 characters, direct=1
0000 fe fe e0 8c 1c 00 00 fd ........
icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=00
6:frame.c(403):icom_one_transaction returning(0)
5:frame.c(455):icom_transaction returning(0)
4:icom.c(5099):icom_get_ptt returning(0)
3:rig_get_ptt: elapsed=61ms
3:rig.c(3401):rig_get_ptt returning(0)
2:rigctl_parse.c(2624):rigctl_get_ptt returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=f(66) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): f 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2128):rigctl_get_freq entered
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: Main cache hit age=259ms, freq=145987500, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
2:rigctl_parse.c(2154):rigctl_get_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=i(69) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): i 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2909):rigctl_get_split_freq entered
3:rig.c(4215):rig_get_split_freq entered
vfo_fixup:(from rig_get_split_freq:4229) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
rig.c(4272) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from Main to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(4303) trace
icom_get_freq called for currVFO, curr_vfo=Sub
icom_get_freq: using vfo=currVFO
icom.c(1671) trace
set_vfo_curr: vfo=currVFO, curr_vfo=Sub
set_vfo_curr: Asking for currVFO, currVFO=Sub
icom.c(9428):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=currVFO, curr_vfo=Sub
icom.c(1783):icom_get_freq returning2(0)
rig_get_split_freq: restoring vfo=Main
rig.c(4317) trace
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
icom_set_vfo called vfo=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_get_split_freq: tx_freq=437800000
3:rig_get_split_freq: elapsed=137ms
3:rig.c(4343):rig_get_split_freq returning(0)
2:rigctl_parse.c(2925):rigctl_get_split_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=I(49) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): I 'VFOA' '145987675' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2894):rigctl_set_split_freq entered
rig_set_split_freq called vfo=TX, curr_vfo=Main, tx_freq=145987675
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: Main cache hit age=398ms, freq=145987500, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
vfo_fixup:(from rig_set_split_freq:4092) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
rig.c(4105) trace
rig_set_freq called vfo=Main, freq=145987675
vfo_fixup:(from rig_set_freq:1785) vfo=Main, vfo_curr=Main, split=1
rig_set_freq: TARGETABLE_FREQ vfo=Main
rig.c(1813) trace
icom_set_freq called Main=145987675
icom.c(1324) trace
icom_set_freq: set_vfo_curr=Main
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: Main cache hit age=398ms, freq=145987500, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
to_bcd called
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x00, subcmd=0xffffffff, payload_len=5
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 11 bytes, method=2
0000 fe fe 8c e0 00 75 76 98 45 01 fd .....uv.E..
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe 8c e0 00 75 76 98 45 01 fd .....uv.E..
icom_one_transaction: DEBUG retval=11, frm_len=11, cmd=0x00
4:frame.c(261):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
rig.c(1923) trace
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: cache miss age=10000ms, cached_vfo=Main, asked_vfo=Main, use_cached_freq=0
rig_get_freq(2091): vfo_opt=1, model=3071
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
2:rig_get_freq: elapsed=48ms
rig_set_freq: Asked freq=145987675, got freq=145987500
rig.c(1949) trace
3:rig.c(2733):rig_set_vfo entered
rig_set_vfo called vfo=Main
vfo_fixup:(from rig_set_vfo:2753) vfo=Main, vfo_curr=Main, split=1
vfo_fixup:(from rig_set_vfo:2795) vfo=Main, vfo_curr=Main, split=1
rig.c(2814) trace
icom_set_vfo called vfo=Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_set_vfo: rig->state.current_vfo=Main
rig.c(2840) trace
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
rig_set_vfo: retcode from rig_get_freq = 0
rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
3:rig_set_vfo: elapsed=126ms
3:rig.c(2865):rig_set_vfo returning(0)
2:rig_set_freq: elapsed=205ms
rig.c(1954):rig_set_freq returning2(0)
2:rig_set_split_freq: elapsed=205ms
rig.c(4119):rig_set_split_freq returning2(0)
2:rigctl_parse.c(2898):rigctl_set_split_freq returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=i(69) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): i 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2909):rigctl_get_split_freq entered
3:rig.c(4215):rig_get_split_freq entered
vfo_fixup:(from rig_get_split_freq:4229) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
rig.c(4272) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from Main to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(4303) trace
icom_get_freq called for currVFO, curr_vfo=Sub
icom_get_freq: using vfo=currVFO
icom.c(1671) trace
set_vfo_curr: vfo=currVFO, curr_vfo=Sub
set_vfo_curr: Asking for currVFO, currVFO=Sub
icom.c(9428):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=currVFO, curr_vfo=Sub
icom.c(1783):icom_get_freq returning2(0)
rig_get_split_freq: restoring vfo=Main
rig.c(4317) trace
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
icom_set_vfo called vfo=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_get_split_freq: tx_freq=437800000
3:rig_get_split_freq: elapsed=166ms
3:rig.c(4343):rig_get_split_freq returning(0)
2:rigctl_parse.c(2925):rigctl_get_split_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=t(74) handle=3
rig_parse_vfo called
rig_parse_vfo: 'vfoCurr' not found so vfo='None'
mutex_rigctld: client lock engaged
rigctl(d): t 'None' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2607):rigctl_get_ptt entered
3:rig.c(3293):rig_get_ptt entered
rig_get_ptt: cache check age=13875ms
rig_get_ptt: cache miss age=13875ms
rig.c(3359) trace
rig.c(3383) trace
4:icom.c(5076):icom_get_ptt entered
5:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x1c, subcmd=0x00, payload_len=0
6:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 1c 00 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 1c 00 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x1c
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 8 characters, direct=1
0000 fe fe e0 8c 1c 00 00 fd ........
icom_one_transaction: frm_len=8, frm_len-1=fd, frm_len-2=00
6:frame.c(403):icom_one_transaction returning(0)
5:frame.c(455):icom_transaction returning(0)
4:icom.c(5099):icom_get_ptt returning(0)
3:rig_get_ptt: elapsed=63ms
3:rig.c(3401):rig_get_ptt returning(0)
2:rigctl_parse.c(2624):rigctl_get_ptt returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=f(66) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): f 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2128):rigctl_get_freq entered
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: cache miss age=3765ms, cached_vfo=Main, asked_vfo=Main, use_cached_freq=0
rig_get_freq(2091): vfo_opt=1, model=3071
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
2:rig_get_freq: elapsed=63ms
2:rigctl_parse.c(2154):rigctl_get_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=F(46) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): F 'currVFO' '145982858' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2104):rigctl_set_freq entered
rig_set_freq called vfo=currVFO, freq=145982858
vfo_fixup:(from rig_set_freq:1785) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig_set_freq: TARGETABLE_FREQ vfo=currVFO
rig.c(1813) trace
icom_set_freq called currVFO=145982858
icom.c(1324) trace
icom_set_freq: set_vfo_curr=Main
set_vfo_curr: vfo=currVFO, curr_vfo=Main
set_vfo_curr: Asking for currVFO, currVFO=Main
icom.c(9428):set_vfo_curr returning2(0)
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: Main cache hit age=1ms, freq=145987500, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
to_bcd called
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x00, subcmd=0xffffffff, payload_len=5
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 11 bytes, method=2
0000 fe fe 8c e0 00 58 28 98 45 01 fd .....X(.E..
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe 8c e0 00 58 28 98 45 01 fd .....X(.E..
icom_one_transaction: DEBUG retval=11, frm_len=11, cmd=0x00
4:frame.c(261):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
rig.c(1923) trace
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: cache miss age=10000ms, cached_vfo=Main, asked_vfo=Main, use_cached_freq=0
rig_get_freq(2091): vfo_opt=1, model=3071
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 50 12 98 45 01 fd .....P..E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
2:rig_get_freq: elapsed=62ms
rig_set_freq: Asked freq=145982858, got freq=145981250
2:rig_set_freq: elapsed=107ms
rig.c(1954):rig_set_freq returning2(0)
2:rigctl_parse.c(2116):rigctl_set_freq returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=f(66) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): f 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2128):rigctl_get_freq entered
rig_get_freq(1995) called vfo=currVFO
vfo_fixup:(from rig_get_freq:2002) vfo=currVFO, vfo_curr=Main, split=1
vfo_fixup: Leaving currVFO alone
rig.c(2004) vfo=currVFO, curr_vfo=Main
rig_get_freq: Main cache hit age=15ms, freq=145981250, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
2:rigctl_parse.c(2154):rigctl_get_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=i(69) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): i 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2909):rigctl_get_split_freq entered
3:rig.c(4215):rig_get_split_freq entered
vfo_fixup:(from rig_get_split_freq:4229) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
rig.c(4272) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from Main to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(4303) trace
icom_get_freq called for currVFO, curr_vfo=Sub
icom_get_freq: using vfo=currVFO
icom.c(1671) trace
set_vfo_curr: vfo=currVFO, curr_vfo=Sub
set_vfo_curr: Asking for currVFO, currVFO=Sub
icom.c(9428):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=currVFO, curr_vfo=Sub
icom.c(1783):icom_get_freq returning2(0)
rig_get_split_freq: restoring vfo=Main
rig.c(4317) trace
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
icom_set_vfo called vfo=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_get_split_freq: tx_freq=437800000
3:rig_get_split_freq: elapsed=158ms
3:rig.c(4343):rig_get_split_freq returning(0)
2:rigctl_parse.c(2925):rigctl_get_split_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=I(49) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): I 'VFOA' '145987670' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2894):rigctl_set_split_freq entered
rig_set_split_freq called vfo=TX, curr_vfo=Main, tx_freq=145987670
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: Main cache hit age=174ms, freq=145981250, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
vfo_fixup:(from rig_set_split_freq:4092) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
rig.c(4105) trace
rig_set_freq called vfo=Main, freq=145987670
vfo_fixup:(from rig_set_freq:1785) vfo=Main, vfo_curr=Main, split=1
rig_set_freq: TARGETABLE_FREQ vfo=Main
rig.c(1813) trace
icom_set_freq called Main=145987670
icom.c(1324) trace
icom_set_freq: set_vfo_curr=Main
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: Main cache hit age=175ms, freq=145981250, use_cached_freq=0
2:rig_get_freq: elapsed=0ms
to_bcd called
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x00, subcmd=0xffffffff, payload_len=5
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 11 bytes, method=2
0000 fe fe 8c e0 00 70 76 98 45 01 fd .....pv.E..
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe 8c e0 00 70 76 98 45 01 fd .....pv.E..
icom_one_transaction: DEBUG retval=11, frm_len=11, cmd=0x00
4:frame.c(261):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
rig.c(1923) trace
rig_get_freq(1995) called vfo=Main
vfo_fixup:(from rig_get_freq:2002) vfo=Main, vfo_curr=Main, split=1
rig.c(2004) vfo=Main, curr_vfo=Main
rig_get_freq: cache miss age=10000ms, cached_vfo=Main, asked_vfo=Main, use_cached_freq=0
rig_get_freq(2091): vfo_opt=1, model=3071
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
3:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
4:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
4:frame.c(403):icom_one_transaction returning(0)
3:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
2:rig_get_freq: elapsed=48ms
rig_set_freq: Asked freq=145987670, got freq=145987500
rig.c(1949) trace
3:rig.c(2733):rig_set_vfo entered
rig_set_vfo called vfo=Main
vfo_fixup:(from rig_set_vfo:2753) vfo=Main, vfo_curr=Main, split=1
vfo_fixup:(from rig_set_vfo:2795) vfo=Main, vfo_curr=Main, split=1
rig.c(2814) trace
icom_set_vfo called vfo=Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_set_vfo: rig->state.current_vfo=Main
rig.c(2840) trace
icom_get_freq called for Main, curr_vfo=Main
icom_get_freq: using vfo=Main
icom.c(1671) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 75 98 45 01 fd ......u.E..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=01
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Main, curr_vfo=Main
set_vfo_curr: curr_vfo now=Main
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=Main, curr_vfo=Main
icom.c(1783):icom_get_freq returning2(0)
rig_set_vfo: retcode from rig_get_freq = 0
rig_set_vfo: return 0, vfo=Main, curr_vfo=Main
3:rig_set_vfo: elapsed=96ms
3:rig.c(2865):rig_set_vfo returning(0)
2:rig_set_freq: elapsed=174ms
rig.c(1954):rig_set_freq returning2(0)
2:rig_set_split_freq: elapsed=175ms
rig.c(4119):rig_set_split_freq returning2(0)
2:rigctl_parse.c(2898):rigctl_set_split_freq returning(0)
rigctl_parse: return#2 RPRT 0
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=i(69) handle=3
rig_parse_vfo called
mutex_rigctld: client lock engaged
rigctl(d): i 'currVFO' '' '' ''
rigctl_parse: vfo_opt=1
2:rigctl_parse.c(2909):rigctl_get_split_freq entered
3:rig.c(4215):rig_get_split_freq entered
vfo_fixup:(from rig_get_split_freq:4229) vfo=TX, vfo_curr=Main, split=1
vfo_fixup(1941): split=1, vfo==TX tx_vfo=Main
vfo_fixup: RIG_VFO_TX changed to Sub, split=1, satmode=0
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
rig.c(4272) trace
icom_set_vfo called vfo=Sub
icom_set_vfo: VFO changing from Main to Sub
icom_set_vfo: line#2878
icom_set_vfo: Sub asked for, ended up with vfo=Sub
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd1, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d1 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d1 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Sub
icom.c(3108):icom_set_vfo returning2(0)
rig.c(4303) trace
icom_get_freq called for currVFO, curr_vfo=Sub
icom_get_freq: using vfo=currVFO
icom.c(1671) trace
set_vfo_curr: vfo=currVFO, curr_vfo=Sub
set_vfo_curr: Asking for currVFO, currVFO=Sub
icom.c(9428):set_vfo_curr returning2(0)
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 6 bytes, method=2
0000 fe fe 8c e0 03 fd ......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe 8c e0 03 fd ......
icom_one_transaction: DEBUG retval=6, frm_len=6, cmd=0x03
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 11 characters, direct=1
0000 fe fe e0 8c 03 00 00 80 37 04 fd ........7..
icom_one_transaction: frm_len=11, frm_len-1=fd, frm_len-2=04
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom.c(1682) trace
set_vfo_curr: vfo=Sub, curr_vfo=Sub
set_vfo_curr: curr_vfo now=Sub
icom.c(9482):set_vfo_curr returning2(0)
from_bcd called
icom_get_freq exit vfo=currVFO, curr_vfo=Sub
icom.c(1783):icom_get_freq returning2(0)
rig_get_split_freq: restoring vfo=Main
rig.c(4317) trace
4:rig.c(6199):rig_has_vfo_op entered
4:rig.c(6207):rig_has_vfo_op returning(0)
icom_set_vfo called vfo=Main
icom_set_vfo: VFO changing from Sub to Main
icom_set_vfo: line#2878
icom_set_vfo: line#3081
4:frame.c(425):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0xd0, payload_len=0
5:frame.c(138):icom_one_transaction entered
rig_flush: called for serial device
read_string_generic called, rxmax=4095 direct=1, expected_len=1
write_block(): TX 7 bytes, method=2
0000 fe fe 8c e0 07 d0 fd .......
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 7 characters, direct=1
0000 fe fe 8c e0 07 d0 fd .......
icom_one_transaction: DEBUG retval=7, frm_len=7, cmd=0x07
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): RX 6 characters, direct=1
0000 fe fe e0 8c fb fd ......
icom_one_transaction: frm_len=6, frm_len-1=fd, frm_len-2=fb
5:frame.c(403):icom_one_transaction returning(0)
4:frame.c(455):icom_transaction returning(0)
icom_set_vfo: line#3084
icom_set_vfo: line#3106 curr_vfo=Main
icom.c(3108):icom_set_vfo returning2(0)
rig_get_split_freq: tx_freq=437800000
3:rig_get_split_freq: elapsed=175ms
3:rig.c(4343):rig_get_split_freq returning(0)
2:rigctl_parse.c(2925):rigctl_get_split_freq returning(0)
mutex_rigctld: client lock disengaged
mutex_rigctld: client lock engaged
mutex_rigctld: client lock disengaged
handle_socket: doing rigctl_parse vfo_mode=1, secure=0
rigctl_parse: called, interactive=1
rigctl_parse: cmd=q(71) handle=3
rigctl_parse: quit returning NETRIGCTL_RET 0
handle_socket: rigctl_parse retcode=1
Connection closed from 127.0.0.1:57339