Skip to content
New issue

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

Sign up for GitHub

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

Already on GitHub? Sign in to your account

Jump to bottom

IC-7300 auto_power_on not working #1325

Closed
mdblack98 opened this issue Jun 30, 2023 · 0 comments
Closed

IC-7300 auto_power_on not working #1325

mdblack98 opened this issue Jun 30, 2023 · 0 comments
Labels
bug fixed Issue has been fixed
Milestone
4.6

Comments

@mdblack98
Copy link
Contributor

root@rigpi3:/home/pi# rigctl -m 3073 -r /dev/ttyUSB0 -C=auto_power_on=1 -vvvvv -Z
2023-06-30T07:00:39.655849-0800: rigctl.c(444) Startup: rigctl -m 3073 -r /dev/ttyUSB0 -C=auto_power_on=1 -vvvvv -Z
2023-06-30T07:00:39.656065-0800: rigctl Hamlib 4.6~git from indeterminate source revision. 32-bit
2023-06-30T07:00:39.656634-0800: rig_init: rig_model=Icom IC-7300 20230627.10
2023-06-30T07:00:39.813254-0800: rig_get_freq(2235): vfo_opt=0, model=3073
2023-06-30T07:00:39.813308-0800: icom_get_freq called for VFOA, curr_vfo=None
2023-06-30T07:00:39.813362-0800: 5:icom.c(776):icom_get_usb_echo_off entered
2023-06-30T07:00:39.813418-0800: 6:frame.c(449):icom_transaction entered
2023-06-30T07:00:39.813470-0800: icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
2023-06-30T07:00:39.813528-0800: 7:frame.c(143):icom_one_transaction entered
2023-06-30T07:00:39.813644-0800: write_block(): TX 6 bytes, method=2
2023-06-30T07:00:39.813728-0800: 0000 fe fe 94 e0 03 fd ......
2023-06-30T07:00:41.826243-0800: 7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

2023-06-30T07:00:41.826427-0800: icom_transaction: retry=0: rig_get_freq(2200): freqMainB=0, modeMainB=, widthMainB=0
rig_get_freq: cache miss age=1000000ms, cached_vfo=VFOA, asked_vfo=VFOA, use_cached_freq=0
rig_get_freq(2235): vfo_opt=0, model=3073
icom_get_freq called for VFOA, curr_vfo=None
5:icom.c(776):icom_get_usb_echo_off entered
6:frame.c(449):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
7:frame.c(143):icom_one_transaction entered
write_block(): TX 6 bytes, method=2
0000 fe fe 94 e0 03 fd ......
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out
Communication timed out

2023-06-30T07:00:41.926981-0800: icom_transaction: failed: rig_get_freq(2235): vfo_opt=0, model=3073
icom_get_freq called for VFOA, curr_vfo=None
5:icom.c(776):icom_get_usb_echo_off entered
6:frame.c(449):icom_transaction entered
icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
7:frame.c(143):icom_one_transaction entered
write_block(): TX 6 bytes, method=2
0000 fe fe 94 e0 03 fd ......
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out

Communication timed out
Communication timed out

2023-06-30T07:00:41.928041-0800: 6:frame.c(479):icom_transaction returning(-5) Communication timed out

2023-06-30T07:00:41.928307-0800: 5:icom.c(784):icom_get_usb_echo_off returning(-5) Communication timed out

2023-06-30T07:00:41.928476-0800: icom_get_freq: using vfo=VFOA
2023-06-30T07:00:41.928605-0800: icom_get_freq: TARGETABLE_FREQ and TARGETABLE_MODE enabled
2023-06-30T07:00:41.928735-0800: 5:frame.c(449):icom_transaction entered
2023-06-30T07:00:41.928866-0800: icom_transaction: cmd=0x25, subcmd=0x00, payload_len=0
2023-06-30T07:00:41.929002-0800: 6:frame.c(143):icom_one_transaction entered
2023-06-30T07:00:41.929180-0800: write_block(): TX 7 bytes, method=2
2023-06-30T07:00:41.929327-0800: 0000 fe fe 94 e0 25 00 fd ....%..
2023-06-30T07:00:43.941776-0800: 6:frame.c(318):icom_one_transaction returning(-5) Communication timed out

2023-06-30T07:00:43.941945-0800: icom_transaction: retry=0:
5:icom.c(784):icom_get_usb_echo_off returning(-5) Communication timed out

icom_get_freq: using vfo=VFOA
icom_get_freq: TARGETABLE_FREQ and TARGETABLE_MODE enabled
5:frame.c(449):icom_transaction entered
icom_transaction: cmd=0x25, subcmd=0x00, payload_len=0
6:frame.c(143):icom_one_transaction entered
write_block(): TX 7 bytes, method=2
0000 fe fe 94 e0 25 00 fd ....%..
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
6:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out
Communication timed out

2023-06-30T07:00:44.042464-0800: icom_transaction: failed:
icom_get_freq: using vfo=VFOA
icom_get_freq: TARGETABLE_FREQ and TARGETABLE_MODE enabled
5:frame.c(449):icom_transaction entered
icom_transaction: cmd=0x25, subcmd=0x00, payload_len=0
6:frame.c(143):icom_one_transaction entered
write_block(): TX 7 bytes, method=2
0000 fe fe 94 e0 25 00 fd ....%..
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
6:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out

Communication timed out
Communication timed out

2023-06-30T07:00:44.042769-0800: 5:frame.c(479):icom_transaction returning(-5) Communication timed out

2023-06-30T07:00:44.042924-0800: icom_get_freq: rig probe shows 0x25 CI-V cmd not available for this rig/firmware
2023-06-30T07:00:44.042992-0800: icom.c(1702) trace
2023-06-30T07:00:44.043058-0800: set_vfo_curr: vfo=VFOA, curr_vfo=None
2023-06-30T07:00:44.043119-0800: set_vfo_curr: setting new vfo=VFOA
2023-06-30T07:00:44.043183-0800: icom.c(9577) trace
2023-06-30T07:00:44.043324-0800: 5:rig.c(2893):rig_set_vfo entered
2023-06-30T07:00:44.043390-0800: rig_set_vfo called vfo=VFOA
2023-06-30T07:00:44.043455-0800: vfo_fixup:(from rig_set_vfo:2913) vfo=VFOA, vfo_curr=None, split=0
2023-06-30T07:00:44.043524-0800: vfo_fixup:(from rig_set_vfo:2955) vfo=VFOA, vfo_curr=None, split=0
2023-06-30T07:00:44.043588-0800: rig.c(2974) trace
2023-06-30T07:00:44.043648-0800: icom_set_vfo called vfo=VFOA
2023-06-30T07:00:44.043708-0800: icom_set_vfo: line#2932
2023-06-30T07:00:44.043767-0800: icom_set_vfo: line#3138
2023-06-30T07:00:44.043826-0800: 6:frame.c(449):icom_transaction entered
2023-06-30T07:00:44.043890-0800: icom_transaction: cmd=0x07, subcmd=0x00, payload_len=0
2023-06-30T07:00:44.043953-0800: 7:frame.c(143):icom_one_transaction entered
2023-06-30T07:00:44.044071-0800: write_block(): TX 7 bytes, method=2
2023-06-30T07:00:44.044146-0800: 0000 fe fe 94 e0 07 00 fd .......
2023-06-30T07:00:46.056603-0800: 7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

2023-06-30T07:00:46.056784-0800: icom_transaction: retry=0: vfo_fixup:(from rig_set_vfo:2955) vfo=VFOA, vfo_curr=None, split=0
rig.c(2974) trace
icom_set_vfo called vfo=VFOA
icom_set_vfo: line#2932
icom_set_vfo: line#3138
6:frame.c(449):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0x00, payload_len=0
7:frame.c(143):icom_one_transaction entered
write_block(): TX 7 bytes, method=2
0000 fe fe 94 e0 07 00 fd .......
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out
Communication timed out

2023-06-30T07:00:46.157272-0800: icom_transaction: failed: icom_set_vfo called vfo=VFOA
icom_set_vfo: line#2932
icom_set_vfo: line#3138
6:frame.c(449):icom_transaction entered
icom_transaction: cmd=0x07, subcmd=0x00, payload_len=0
7:frame.c(143):icom_one_transaction entered
write_block(): TX 7 bytes, method=2
0000 fe fe 94 e0 07 00 fd .......
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out

Communication timed out
Communication timed out

2023-06-30T07:00:46.157597-0800: 6:frame.c(479):icom_transaction returning(-5) Communication timed out

2023-06-30T07:00:46.157753-0800: icom_set_vfo: line#3141
2023-06-30T07:00:46.157829-0800: icom.c(3145):icom_set_vfo returning2(-5) Communication timed out

2023-06-30T07:00:46.157962-0800: rig_set_vfo: set_vfo VFOA failed with '0000 fe fe 94 e0 07 00 fd .......
read_string_generic called, rxmax=200 direct=0, expected_len=1
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(1348): retrying read timeout 1/1 timeout=1000
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=1
read_string_generic called, rxmax=200 direct=1, expected_len=1
read_string_generic(): Timed out 2.012 seconds after 0 chars, direct=0
7:frame.c(318):icom_one_transaction returning(-5) Communication timed out

Communication timed out

Communication timed out

 6:frame.c(479):icom_transaction returning(-5) Communication timed out

icom_set_vfo: line#3141
icom.c(3145):icom_set_vfo returning2(-5) Communication timed out

Communication timed out
Communication timed out
'
2023-06-30T07:00:46.158355-0800: 6:rig.c(3053):rig_get_vfo entered
2023-06-30T07:00:46.158424-0800: rig_get_vfo: no get_vfo
2023-06-30T07:00:46.158503-0800: 6:rig_get_vfo: elapsed=0ms
2023-06-30T07:00:46.158581-0800: 6:rig.c(3076):rig_get_vfo returning(-11) Feature not available

2023-06-30T07:00:46.158651-0800: rig_set_vfo: Expiring all cache due to VFO change and no get_vfo
2023-06-30T07:00:46.158743-0800: rig_set_vfo: return -5, vfo=VFOA, curr_vfo=None
2023-06-30T07:00:46.158821-0800: 5:rig_set_vfo: elapsed=2116ms
2023-06-30T07:00:46.158899-0800: 5:rig.c(3028):rig_set_vfo returning(-5) Communication timed out

2023-06-30T07:00:46.158978-0800: icom.c(9582):set_vfo_curr returning2(-5) Communication timed out

2023-06-30T07:00:46.159059-0800: 4:icom.c(1709):icom_get_freq returning(-5) Communication timed out

2023-06-30T07:00:46.159265-0800: 3:rig_get_freq: elapsed=6346ms
2023-06-30T07:00:46.159343-0800: 3:rig.c(2342):rig_get_freq returning(-5) Communication timed out

2023-06-30T07:00:46.159422-0800: 2:rig.c(6298):rig_get_powerstat returning(-5) Communication timed out

2023-06-30T07:00:46.159497-0800: rig_open: Some rigs cannot get_powerstat while off
2023-06-30T07:00:46.159564-0800: rig_open: Known rigs: K3, K3S
2023-06-30T07:00:46.159624-0800: 2:icom.c(988):icom_rig_open entered
2023-06-30T07:00:46.159683-0800: icom_rig_open: IC-7300 v20230627.10
2023-06-30T07:00:46.159747-0800: 3:icom.c(776):icom_get_usb_echo_off entered
2023-06-30T07:00:46.159813-0800: 4:frame.c(449):icom_transaction entered
2023-06-30T07:00:46.159879-0800: icom_transaction: cmd=0x03, subcmd=0xffffffff, payload_len=0
2023-06-30T07:00:46.159943-0800: 5:frame.c(143):icom_one_transaction entered
2023-06-30T07:00:46.160054-0800: write_block(): TX 6 bytes, method=2
2023-06-30T07:00:46.160128-0800: 0000 fe fe 94 e0 03 fd ......
2023-06-30T07:00:48.172543-0800: 5:frame.c(318):icom_one_transaction returning(-5) Communication timed out
@mdblack98 mdblack98 added the bug label Jun 30, 2023
@mdblack98 mdblack98 added this to the 4.6 milestone Jun 30, 2023
mdblack98 added a commit that referenced this issue Jul 16, 2023
@mdblack98
Improve Icom set_powerstat=1
902cfd7 
Original 2023-07-16T09:28:43.323319-0600:    4:rig_set_powerstat: elapsed=10236ms
IC-7300  2023-07-16T09:46:48.814844-0600:    4:rig_set_powerstat: elapsed=4020ms
IC-9700  2023-07-16T10:36:27.239828-0600:    4:rig_set_powerstat: elapsed=5717ms
The goal is to tweak each rig for fastest result but need more testers for that
Slowest right now is IC-9700 which is the default value
Total timeout will be just over 10.5 seconds to allow rig to power on
#1325
@mdblack98 mdblack98 added needs test Patches have been submitted but need testing to close issue fixed Issue has been fixed and removed needs test Patches have been submitted but need testing to close issue labels Jul 16, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug fixed Issue has been fixed
Projects
None yet
Milestone
4.6
Development

No branches or pull requests
1 participant
@mdblack98