locked WSJT-X sending incorrect command to FT-991a #Cat_RigControl


SteveO - KC5NK
 

Previously had issue with MD command over CAT. Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally: VS1;, VS0; and VS; commands over the serial port. The radio does not recognize these commands.

From my logs:
20220430_19:46:02.142 Z ** SYS | EXT CAT, unexpected command: VS1;
20220430_19:46:02.142 Z ** SYS | EXT CAT, unexpected command: VS;
20220430_19:46:04.854 Z ** SYS | EXT CAT, unexpected command: VS0;
20220430_19:46:04.855 Z ** SYS | EXT CAT, unexpected command: VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a. I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB

On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote:

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

Please place this file as described belowhttps://www.dropbox.com/s/t52ngcalsgnpm8m/wsjtx_log_config.ini?dl=0
C:\Users\[username]\AppData\Local\WSJT-X The WSJT-X_Rigcontrol.log file will be in the same location For Linux put it in   ~/.config The WSJT-X_Rigcontrol.log file will be here: ~/.local/share/WSJT-X Restart WSJT-X and duplicate the problem.Shut down WSJT-X
Then send me the WSJT-X_RigControl.log fileMike W9MDB

On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote:

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

On Saturday, April 30, 2022, 03:22:46 PM CDT, Black Michael <mdblack98@...> wrote:





Please place this file as described below
https://www.dropbox.com/s/t52ngcalsgnpm8m/wsjtx_log_config.ini?dl=0

C:\Users\[username]\AppData\Local\WSJT-X
The WSJT-X_Rigcontrol.log file will be in the same location

For Linux put it in  
~/.config
The WSJT-X_Rigcontrol.log file will be here:
~/.local/share/WSJT-X

Restart WSJT-X and duplicate the problem.
Shut down WSJT-X

Then send me the WSJT-X_RigControl.log file
Mike W9MDB









On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


SteveO - KC5NK
 

It just would generate an error. But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio. My application allows me to control the radio, while not interfering with WSJT-x. I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses. VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood. If it doesn’t bother you, it doesn’t bother me. I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio. Sort of a failsafe …. Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote:

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB

On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


SteveO - KC5NK
 

Michael,

I did see your e-mails after my response.

I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

Prior to changing the DLL, it always happened on a band change from say 40m to 20m. I had confirmed that after submitting issue.

Bear with me, I will keep trying various changes to see if I can trigger it. If not, we may chalk it up to some changes in the DLL?

Best regards,
Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

Yes...band change was using VS even if the rig didn't have it....so only rigs that have it will execute that command now.

Mike W9MDB

On Saturday, April 30, 2022, 04:35:08 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Michael,

  I did see your e-mails after my response.

  I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

  Prior to changing the DLL, it always happened on a band change from say 40m to 20m.  I had confirmed that after submitting issue.

  Bear with me, I will keep trying various changes to see if I can trigger it.  If not, we may chalk it up to some changes in the DLL?

Best regards,
    Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


SteveO - KC5NK
 

Mike,

Okay, so I did get the log file and although the command is never sent to the radio, I do see it in the log.

I will attach log, but just in case it will not post, I’ll paste the snippet here.

[2022-04-30 21:39:39.386775][00:03:12.237178][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-04-30 21:39:39.386923][00:03:12.237236][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-04-30 21:39:39.386923][00:03:12.237279][RIGCTRL:trace] newcat_valid_command VS
[2022-04-30 21:39:39.386923][00:03:12.237328][RIGCTRL:debug] 13:newcat.c(9957):newcat_get_rigid entered
[2022-04-30 21:39:39.386923][00:03:12.237368][RIGCTRL:trace] rig_id = 670
[2022-04-30 21:39:39.386923][00:03:12.237427][RIGCTRL:debug] 13:newcat.c(9978):newcat_get_rigid returning(670)
[2022-04-30 21:39:39.386923][00:03:12.237483][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported

This happens a few times in the file, but the COM port never sees the command now.

Best regards,
Steve KC5NK

PS: Do you ever sleep or take a day off? Thanks for your very quick responses!

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:37 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Yes...band change was using VS even if the rig didn't have it....so only rigs that have it will execute that command now.

Mike W9MDB








On Saturday, April 30, 2022, 04:35:08 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Michael,

  I did see your e-mails after my response.

  I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

  Prior to changing the DLL, it always happened on a band change from say 40m to 20m.  I had confirmed that after submitting issue.

  Bear with me, I will keep trying various changes to see if I can trigger it.  If not, we may chalk it up to some changes in the DLL?

Best regards,
    Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

Can I get more of log so I can see where it's being called?

Not quite enough history there.

Mike W9MDB

On Sunday, May 1, 2022, 12:22:52 AM CDT, SteveO via groups.io <sollmann22@...> wrote:





Mike,

  Okay, so I did get the log file and although the command is never sent to the radio, I do see it in the log.

  I will attach log, but just in case it will not post, I’ll paste the snippet here.

[2022-04-30 21:39:39.386775][00:03:12.237178][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-04-30 21:39:39.386923][00:03:12.237236][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-04-30 21:39:39.386923][00:03:12.237279][RIGCTRL:trace] newcat_valid_command VS
[2022-04-30 21:39:39.386923][00:03:12.237328][RIGCTRL:debug] 13:newcat.c(9957):newcat_get_rigid entered
[2022-04-30 21:39:39.386923][00:03:12.237368][RIGCTRL:trace] rig_id = 670
[2022-04-30 21:39:39.386923][00:03:12.237427][RIGCTRL:debug] 13:newcat.c(9978):newcat_get_rigid returning(670)
[2022-04-30 21:39:39.386923][00:03:12.237483][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
 
This happens a few times in the file, but the COM port never sees the command now.

Best regards,
    Steve  KC5NK

PS:  Do you ever sleep or take a day off?  Thanks for your very quick responses!

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:37 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Yes...band change was using VS even if the rig didn't have it....so only rigs that have it will execute that command now.

Mike W9MDB








On Saturday, April 30, 2022, 04:35:08 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Michael,

  I did see your e-mails after my response.

  I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

  Prior to changing the DLL, it always happened on a band change from say 40m to 20m.  I had confirmed that after submitting issue.

  Bear with me, I will keep trying various changes to see if I can trigger it.  If not, we may chalk it up to some changes in the DLL?

Best regards,
    Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


Michael Black
 

That is just a debug message when it checks to see if VS is available.

So it is expected....notice that it's a trace message and not a warning.

Mike W9MDB

On Sunday, May 1, 2022, 12:22:52 AM CDT, SteveO via groups.io <sollmann22@...> wrote:





Mike,

  Okay, so I did get the log file and although the command is never sent to the radio, I do see it in the log.

  I will attach log, but just in case it will not post, I’ll paste the snippet here.

[2022-04-30 21:39:39.386775][00:03:12.237178][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-04-30 21:39:39.386923][00:03:12.237236][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-04-30 21:39:39.386923][00:03:12.237279][RIGCTRL:trace] newcat_valid_command VS
[2022-04-30 21:39:39.386923][00:03:12.237328][RIGCTRL:debug] 13:newcat.c(9957):newcat_get_rigid entered
[2022-04-30 21:39:39.386923][00:03:12.237368][RIGCTRL:trace] rig_id = 670
[2022-04-30 21:39:39.386923][00:03:12.237427][RIGCTRL:debug] 13:newcat.c(9978):newcat_get_rigid returning(670)
[2022-04-30 21:39:39.386923][00:03:12.237483][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
 
This happens a few times in the file, but the COM port never sees the command now.

Best regards,
    Steve  KC5NK

PS:  Do you ever sleep or take a day off?  Thanks for your very quick responses!

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:37 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Yes...band change was using VS even if the rig didn't have it....so only rigs that have it will execute that command now.

Mike W9MDB








On Saturday, April 30, 2022, 04:35:08 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Michael,

  I did see your e-mails after my response.

  I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

  Prior to changing the DLL, it always happened on a band change from say 40m to 20m.  I had confirmed that after submitting issue.

  Bear with me, I will keep trying various changes to see if I can trigger it.  If not, we may chalk it up to some changes in the DLL?

Best regards,
    Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


SteveO - KC5NK
 

Mike,

Here is a larger snippet…

[2022-05-01 16:51:43.276230][00:00:32.589509][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589515][RIGCTRL:debug] rig_set_split_vfo: final rxvfo=VFOA, txvfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589521][RIGCTRL:trace] ../../src/src/rig.c(4541) trace
[2022-05-01 16:51:43.276230][00:00:32.589528][RIGCTRL:debug] rig_set_split_vfo(4560): split already set...ignoring
[2022-05-01 16:51:43.276230][00:00:32.589536][RIGCTRL:debug] 4:rig.c(4561):rig_set_split_vfo returning(0)
[2022-05-01 16:51:43.276230][00:00:32.589541][RIGCTRL:trace] state: true
[2022-05-01 16:51:43.276230][00:00:32.589545][RIGCTRL:trace] rig_set_split_freq_mode freq=7074000 mode = PKTUSB
[2022-05-01 16:51:43.276230][00:00:32.589555][RIGCTRL:debug] 4:rig.c(4314):rig_set_split_freq_mode entered
[2022-05-01 16:51:43.276230][00:00:32.589564][RIGCTRL:trace] vfo_fixup:(from rig_set_split_freq_mode:4336) vfo=TX, vfo_curr=VFOA, split=1
[2022-05-01 16:51:43.276230][00:00:32.589571][RIGCTRL:debug] vfo_fixup(1936): split=1, vfo==TX tx_vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589580][RIGCTRL:trace] vfo_fixup: RIG_VFO_TX changed to VFOB, split=1, satmode=0
[2022-05-01 16:51:43.276230][00:00:32.589594][RIGCTRL:debug] rig_set_split_freq_mode: vfo=VFOB, tx_freq=7074000, tx_mode=PKTUSB, tx_width=-1
[2022-05-01 16:51:43.276230][00:00:32.589600][RIGCTRL:trace] ../../src/src/rig.c(4382) trace
[2022-05-01 16:51:43.276230][00:00:32.589611][RIGCTRL:debug] rig_set_split_freq called vfo=VFOB, curr_vfo=VFOA, tx_freq=7074000
[2022-05-01 16:51:43.276230][00:00:32.589618][RIGCTRL:debug] rig_get_freq(1774) called vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589633][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:1780) vfo=VFOB, vfo_curr=VFOA, split=1
[2022-05-01 16:51:43.276230][00:00:32.589638][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589645][RIGCTRL:debug] ../../src/src/rig.c(1783) vfo=VFOB, curr_vfo=VFOA
[2022-05-01 16:51:43.277261][00:00:32.589671][RIGCTRL:trace] rig_get_freq: VFOB cache hit age=287ms, freq=14074000
[2022-05-01 16:51:43.277261][00:00:32.589678][RIGCTRL:trace] 4:rig_get_freq: elapsed=1ms
[2022-05-01 16:51:43.277261][00:00:32.589684][RIGCTRL:trace] ../../src/src/rig.c(3705) trace
[2022-05-01 16:51:43.277261][00:00:32.589689][RIGCTRL:debug] ft991_set_split_freq called
[2022-05-01 16:51:43.277261][00:00:32.589694][RIGCTRL:debug] ft991_get_tx_split called
[2022-05-01 16:51:43.277261][00:00:32.589701][RIGCTRL:debug] 5:newcat.c(7454):newcat_get_tx_vfo entered
[2022-05-01 16:51:43.277261][00:00:32.589708][RIGCTRL:trace] newcat_valid_command FT
[2022-05-01 16:51:43.277261][00:00:32.589716][RIGCTRL:debug] 6:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.277261][00:00:32.589729][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.277261][00:00:32.589737][RIGCTRL:debug] 6:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.277261][00:00:32.589745][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.277261][00:00:32.589752][RIGCTRL:debug] 6:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:43.277261][00:00:32.589760][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.277261][00:00:32.589766][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.288397][00:00:32.600968][RIGCTRL:trace] cmd_str = FT;
[2022-05-01 16:51:43.288397][00:00:32.601227][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:43.288397][00:00:32.601244][RIGCTRL:trace] 0000 46 54 3b FT;
[2022-05-01 16:51:43.314230][00:00:32.626751][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.315230][00:00:32.628142][RIGCTRL:trace] read_string_generic(): RX 4 characters, direct=1
[2022-05-01 16:51:43.315230][00:00:32.628158][RIGCTRL:trace] 0000 46 54 30 3b FT0;
[2022-05-01 16:51:43.315230][00:00:32.628165][RIGCTRL:trace] newcat_get_cmd: read count = 4, ret_data = FT0;
[2022-05-01 16:51:43.315230][00:00:32.628173][RIGCTRL:debug] 6:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:43.315230][00:00:32.628178][RIGCTRL:debug] 6:newcat.c(9995):newcat_get_vfo_mode entered
[2022-05-01 16:51:43.315230][00:00:32.628182][RIGCTRL:trace] newcat_valid_command IF
[2022-05-01 16:51:43.315230][00:00:32.628188][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.315230][00:00:32.628192][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.315230][00:00:32.628198][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.315230][00:00:32.628203][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.315230][00:00:32.628209][RIGCTRL:debug] 7:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:43.315230][00:00:32.628221][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.315230][00:00:32.628227][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.326260][00:00:32.638944][RIGCTRL:trace] cmd_str = IF;
[2022-05-01 16:51:43.326260][00:00:32.639263][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:43.326260][00:00:32.639279][RIGCTRL:trace] 0000 49 46 3b IF;
[2022-05-01 16:51:43.352539][00:00:32.665056][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.358186][00:00:32.670815][RIGCTRL:debug] read_string_generic: minlen now 28
[2022-05-01 16:51:43.358186][00:00:32.670837][RIGCTRL:trace] read_string_generic(): RX 28 characters, direct=1
[2022-05-01 16:51:43.358186][00:00:32.670846][RIGCTRL:trace] 0000 49 46 31 32 37 30 30 37 30 37 34 30 30 30 2b 30 IF127007074000+0
[2022-05-01 16:51:43.358186][00:00:32.670853][RIGCTRL:trace] 0010 30 30 30 30 30 43 30 30 30 30 30 3b 00000C00000;
[2022-05-01 16:51:43.358186][00:00:32.670860][RIGCTRL:trace] newcat_get_cmd: read count = 28, ret_data = IF127007074000+000000C00000;
[2022-05-01 16:51:43.358186][00:00:32.670870][RIGCTRL:debug] 7:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670878][RIGCTRL:trace] newcat_get_vfo_mode: offset=22, width_frequency=9
[2022-05-01 16:51:43.358186][00:00:32.670883][RIGCTRL:trace] newcat_get_vfo_mode: vfo mode = VFO
[2022-05-01 16:51:43.358186][00:00:32.670901][RIGCTRL:debug] 6:newcat.c(10050):newcat_get_vfo_mode returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670906][RIGCTRL:trace] newcat_get_tx_vfo: tx_vfo = VFOA
[2022-05-01 16:51:43.358186][00:00:32.670913][RIGCTRL:debug] 5:newcat.c(7515):newcat_get_tx_vfo returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670918][RIGCTRL:debug] 5:newcat.c(7366):newcat_set_tx_vfo entered
[2022-05-01 16:51:43.358186][00:00:32.670925][RIGCTRL:trace] newcat_valid_command FT
[2022-05-01 16:51:43.358186][00:00:32.670930][RIGCTRL:debug] 6:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.358186][00:00:32.670934][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.358186][00:00:32.670941][RIGCTRL:debug] 6:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.358186][00:00:32.670951][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.358186][00:00:32.670962][RIGCTRL:debug] 6:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:43.358186][00:00:32.670970][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOB
[2022-05-01 16:51:43.358186][00:00:32.670979][RIGCTRL:debug] 6:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670988][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(7421) trace
[2022-05-01 16:51:43.358186][00:00:32.670994][RIGCTRL:trace] cmd_str = FT3;, vfo=VFOB
[2022-05-01 16:51:43.358186][00:00:32.671000][RIGCTRL:debug] 6:newcat.c(10502):newcat_set_cmd entered
[2022-05-01 16:51:43.358186][00:00:32.671009][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.358186][00:00:32.671016][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.371189][00:00:32.683726][RIGCTRL:trace] 0000 46 42 30 31 34 30 37 34 30 30 30 3b FB014074000;
[2022-05-01 16:51:43.371189][00:00:32.683755][RIGCTRL:trace] cmd_str = FT3;
[2022-05-01 16:51:43.371189][00:00:32.683763][RIGCTRL:debug] 7:newcat.c(10338):newcat_set_cmd_validate entered
[2022-05-01 16:51:43.371189][00:00:32.683767][RIGCTRL:trace] newcat_set_cmd_validate: priv->cmd_str=FT3;
[2022-05-01 16:51:43.371189][00:00:32.683773][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.371189][00:00:32.683779][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.381920][00:00:32.694544][RIGCTRL:trace] write_block(): TX 7 bytes, method=2
[2022-05-01 16:51:43.381920][00:00:32.694565][RIGCTRL:trace] 0000 46 54 33 3b 46 54 3b FT3;FT;
[2022-05-01 16:51:43.407225][00:00:32.719636][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.409235][00:00:32.722203][RIGCTRL:trace] read_string_generic(): RX 4 characters, direct=1
[2022-05-01 16:51:43.409235][00:00:32.722227][RIGCTRL:trace] 0000 46 54 31 3b FT1;
[2022-05-01 16:51:43.409235][00:00:32.722233][RIGCTRL:trace] newcat_set_cmd: cmd_validate OK
[2022-05-01 16:51:43.409235][00:00:32.722240][RIGCTRL:debug] 7:newcat.c(10519):newcat_set_cmd returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722246][RIGCTRL:debug] 6:newcat.c(7439):newcat_set_tx_vfo returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722252][RIGCTRL:debug] 6:newcat.c(744):newcat_set_freq entered
[2022-05-01 16:51:43.409235][00:00:32.722261][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:43.409235][00:00:32.722267][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722272][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722277][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722296][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722300][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:43.409235][00:00:32.722305][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722311][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722315][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722320][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722325][RIGCTRL:trace] newcat_set_freq: passed vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722345][RIGCTRL:trace] newcat_set_freq: passed freq = 7074000 Hz
[2022-05-01 16:51:43.409235][00:00:32.722354][RIGCTRL:debug] 7:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:43.409235][00:00:32.722359][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722366][RIGCTRL:debug] 7:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722374][RIGCTRL:trace] newcat_set_freq: special_60m=0, 60m freq=0, is_ftdx3000=0,is_ftdx3000dm=0
[2022-05-01 16:51:43.409235][00:00:32.722382][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(904)newcat_set_freq: rig->state.current_vfo=VFOA
[2022-05-01 16:51:43.409235][00:00:32.722390][RIGCTRL:debug] rig_get_freq(1774) called vfo=VFOB
[2022-05-01 16:51:43.409235][00:00:32.722404][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:1780) vfo=VFOB, vfo_curr=VFOA, split=0
[2022-05-01 16:51:43.409235][00:00:32.722413][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2022-05-01 16:51:43.409235][00:00:32.722417][RIGCTRL:debug] ../../src/src/rig.c(1783) vfo=VFOB, curr_vfo=VFOA
[2022-05-01 16:51:43.409235][00:00:32.722436][RIGCTRL:trace] rig_get_freq: cache miss age=10000ms, cached_vfo=VFOB, asked_vfo=VFOB
[2022-05-01 16:51:43.409235][00:00:32.722442][RIGCTRL:debug] rig_get_freq(1865): vfo_opt=0, model=1035
[2022-05-01 16:51:43.409235][00:00:32.722447][RIGCTRL:debug] 7:newcat.c(1169):newcat_get_freq entered
[2022-05-01 16:51:43.409235][00:00:32.722451][RIGCTRL:trace] newcat_get_freq: passed vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722455][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:43.409235][00:00:32.722461][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722466][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722471][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722476][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722480][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:43.409235][00:00:32.722485][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722490][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722495][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722500][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722505][RIGCTRL:debug] 8:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:43.409235][00:00:32.722509][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722514][RIGCTRL:debug] 8:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722518][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:43.409235][00:00:32.722524][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722527][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722533][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722538][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722542][RIGCTRL:trace] cmd_str = FB;
[2022-05-01 16:51:43.409235][00:00:32.722548][RIGCTRL:debug] 8:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:43.409235][00:00:32.722555][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.409235][00:00:32.722560][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.421259][00:00:32.733763][RIGCTRL:trace] 0000 46 54 31 3b FT1;
[2022-05-01 16:51:43.421259][00:00:32.733780][RIGCTRL:trace] cmd_str = FB;
[2022-05-01 16:51:43.421259][00:00:32.733908][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:43.421259][00:00:32.733922][RIGCTRL:trace] 0000 46 42 3b FB;
[2022-05-01 16:51:43.447149][00:00:32.759548][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.450150][00:00:32.762992][RIGCTRL:debug] read_string_generic: minlen now 12
[2022-05-01 16:51:43.450150][00:00:32.763005][RIGCTRL:trace] read_string_generic(): RX 12 characters, direct=1
[2022-05-01 16:51:43.450150][00:00:32.763012][RIGCTRL:trace] 0000 46 42 30 31 34 30 37 34 30 30 30 3b FB014074000;
[2022-05-01 16:51:43.450150][00:00:32.763018][RIGCTRL:trace] newcat_get_cmd: read count = 12, ret_data = FB014074000;
[2022-05-01 16:51:43.450150][00:00:32.763031][RIGCTRL:debug] 8:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:43.450150][00:00:32.763048][RIGCTRL:trace] newcat_get_freq: freq = 14074000 Hz for vfo VFOB
[2022-05-01 16:51:43.450150][00:00:32.763055][RIGCTRL:debug] 7:newcat.c(1234):newcat_get_freq returning(0)
[2022-05-01 16:51:43.450150][00:00:32.763084][RIGCTRL:trace] 6:rig_get_freq: elapsed=41ms
[2022-05-01 16:51:43.450150][00:00:32.763091][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(923)newcat_set_freq: checking VFOB for band change
[2022-05-01 16:51:43.450150][00:00:32.763102][RIGCTRL:trace] newcat_band_index: freq=7.074e+06, band=3
[2022-05-01 16:51:43.450150][00:00:32.763111][RIGCTRL:trace] newcat_band_index: freq=1.4074e+07, band=5
[2022-05-01 16:51:43.450150][00:00:32.763115][RIGCTRL:trace] newcat_set_freq: VFO_B band changing=1
[2022-05-01 16:51:43.450150][00:00:32.763121][RIGCTRL:trace] newcat_valid_command BS
[2022-05-01 16:51:43.450150][00:00:32.763126][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.450150][00:00:32.763130][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.450150][00:00:32.763135][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.450150][00:00:32.763143][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.450150][00:00:32.763147][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-05-01 16:51:43.450150][00:00:32.763151][RIGCTRL:trace] newcat_valid_command VS
[2022-05-01 16:51:43.450150][00:00:32.763156][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.450150][00:00:32.763161][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.450150][00:00:32.763165][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.450150][00:00:32.763171][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
[2022-05-01 16:51:43.450150][00:00:32.763175][RIGCTRL:debug] newcat.c(7332):newcat_valid_command returning2(0)
[2022-05-01 16:51:43.450150][00:00:32.763180][RIGCTRL:trace] newcat_band_index: freq=7.074e+06, band=3
[2022-05-01 16:51:43.450150][00:00:32.763185][RIGCTRL:debug] 7:newcat.c(10502):newcat_set_cmd entered
[2022-05-01 16:51:43.450150][00:00:32.763191][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.450150][00:00:32.763195][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.461210][00:00:32.773671][RIGCTRL:trace] cmd_str = BS03;
[2022-05-01 16:51:43.461210][00:00:32.773686][RIGCTRL:debug] 8:newcat.c(10338):newcat_set_cmd_validate entered
[2022-05-01 16:51:43.461210][00:00:32.773690][RIGCTRL:trace] newcat_set_cmd_validate: priv->cmd_str=BS03;
[2022-05-01 16:51:43.461210][00:00:32.773695][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.461210][00:00:32.773700][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.472225][00:00:32.784819][RIGCTRL:trace] write_block(): TX 5 bytes, method=2
[2022-05-01 16:51:43.472225][00:00:32.784837][RIGCTRL:trace] 0000 42 53 30 33 3b BS03;
[2022-05-01 16:51:43.498210][00:00:32.810604][RIGCTRL:debug] 8:newcat.c(10437):newcat_set_cmd_validate returning(0)
[2022-05-01 16:51:43.498210][00:00:32.810643][RIGCTRL:trace] newcat_set_cmd: cmd_validate OK
[2022-05-01 16:51:43.498210][00:00:32.810657][RIGCTRL:debug] 7:newcat.c(10519):newcat_set_cmd returning(0)
[2022-05-01 16:51:43.998686][00:00:33.311173][RIGCTRL:trace] newcat_valid_command VS
[2022-05-01 16:51:43.998686][00:00:33.311217][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.998686][00:00:33.311226][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.998686][00:00:33.311236][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.998686][00:00:33.311246][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
[2022-05-01 16:51:43.998686][00:00:33.311257][RIGCTRL:debug] newcat.c(7332):newcat_valid_command returning2(0)
[2022-05-01 16:51:43.998686][00:00:33.311271][RIGCTRL:debug] 7:newcat.c(10502):newcat_set_cmd entered
[2022-05-01 16:51:43.998686][00:00:33.311279][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.998686][00:00:33.311290][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:44.009637][00:00:33.322216][RIGCTRL:trace] cmd_str = BS03;
[2022-05-01 16:51:44.009637][00:00:33.322243][RIGCTRL:debug] 8:newcat.c(10338):newcat_set_cmd_validate entered
[2022-05-01 16:51:44.009637][00:00:33.322249][RIGCTRL:trace] newcat_set_cmd_validate: priv->cmd_str=BS03;
[2022-05-01 16:51:44.009637][00:00:33.322255][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:44.009637][00:00:33.322260][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:44.020537][00:00:33.333535][RIGCTRL:trace] write_block(): TX 5 bytes, method=2
[2022-05-01 16:51:44.020537][00:00:33.333561][RIGCTRL:trace] 0000 42 53 30 33 3b BS03;
[2022-05-01 16:51:44.046545][00:00:33.358934][RIGCTRL:debug] 8:newcat.c(10437):newcat_set_cmd_validate returning(0)
[2022-05-01 16:51:44.046545][00:00:33.358955][RIGCTRL:trace] newcat_set_cmd: cmd_validate OK
[2022-05-01 16:51:44.046545][00:00:33.358962][RIGCTRL:debug] 7:newcat.c(10519):newcat_set_cmd returning(0)
[2022-05-01 16:51:44.046545][00:00:33.358988][RIGCTRL:debug] rig_get_freq(1774) called vfo=Main
[2022-05-01 16:51:44.046545][00:00:33.359002][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:1780) vfo=Main, vfo_curr=VFOA, split=0
[2022-05-01 16:51:44.046545][00:00:33.359008][RIGCTRL:debug] ../../src/src/rig.c(1783) vfo=VFOA, curr_vfo=VFOA
[2022-05-01 16:51:44.046545][00:00:33.359027][RIGCTRL:trace] rig_get_freq: cache miss age=10000ms, cached_vfo=VFOA, asked_vfo=VFOA
[2022-05-01 16:51:44.046545][00:00:33.359031][RIGCTRL:debug] rig_get_freq(1865): vfo_opt=0, model=1035
[2022-05-01 16:51:44.046545][00:00:33.359037][RIGCTRL:debug] 7:newcat.c(1169):newcat_get_freq entered
[2022-05-01 16:51:44.046545][00:00:33.359040][RIGCTRL:trace] newcat_get_freq: passed vfo = VFOA
[2022-05-01 16:51:44.046545][00:00:33.359044][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:44.046545][00:00:33.359053][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.046545][00:00:33.359056][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.046545][00:00:33.359060][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.046545][00:00:33.359065][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.046545][00:00:33.359068][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:44.046545][00:00:33.359078][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.046545][00:00:33.359081][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.046545][00:00:33.359086][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.046545][00:00:33.359090][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.046545][00:00:33.359095][RIGCTRL:debug] 8:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:44.046545][00:00:33.359098][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOA
[2022-05-01 16:51:44.046545][00:00:33.359102][RIGCTRL:debug] 8:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:44.046545][00:00:33.359105][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:44.046545][00:00:33.359109][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.046545][00:00:33.359112][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.046545][00:00:33.359118][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.046545][00:00:33.359122][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.046545][00:00:33.359126][RIGCTRL:trace] cmd_str = FA;
[2022-05-01 16:51:44.046545][00:00:33.359131][RIGCTRL:debug] 8:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:44.046545][00:00:33.359137][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:44.046545][00:00:33.359142][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:44.058747][00:00:33.371373][RIGCTRL:trace] 0000 53 48 30 31 30 3b SH010;
[2022-05-01 16:51:44.058747][00:00:33.371444][RIGCTRL:trace] cmd_str = FA;
[2022-05-01 16:51:44.058747][00:00:33.371778][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:44.058747][00:00:33.371791][RIGCTRL:trace] 0000 46 41 3b FA;
[2022-05-01 16:51:44.085345][00:00:33.397670][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:44.087344][00:00:33.399644][RIGCTRL:debug] read_string_generic: minlen now 12
[2022-05-01 16:51:44.087344][00:00:33.399653][RIGCTRL:trace] read_string_generic(): RX 12 characters, direct=1
[2022-05-01 16:51:44.087344][00:00:33.399660][RIGCTRL:trace] 0000 46 41 30 30 37 30 37 34 30 30 30 3b FA007074000;
[2022-05-01 16:51:44.087344][00:00:33.399666][RIGCTRL:trace] newcat_get_cmd: read count = 12, ret_data = FA007074000;
[2022-05-01 16:51:44.087344][00:00:33.399674][RIGCTRL:debug] 8:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:44.087344][00:00:33.399691][RIGCTRL:trace] newcat_get_freq: freq = 7074000 Hz for vfo VFOA
[2022-05-01 16:51:44.087344][00:00:33.399698][RIGCTRL:debug] 7:newcat.c(1234):newcat_get_freq returning(0)
[2022-05-01 16:51:44.087344][00:00:33.399727][RIGCTRL:trace] 6:rig_get_freq: elapsed=41ms
[2022-05-01 16:51:44.087344][00:00:33.399734][RIGCTRL:debug] 7:rig.c(2135):rig_get_mode entered
[2022-05-01 16:51:44.087344][00:00:33.399741][RIGCTRL:trace] vfo_fixup:(from rig_get_mode:2155) vfo=Main, vfo_curr=VFOA, split=0
[2022-05-01 16:51:44.087344][00:00:33.399754][RIGCTRL:trace] rig_get_mode: VFOA cache check age=10040ms
[2022-05-01 16:51:44.087344][00:00:33.399762][RIGCTRL:trace] rig_get_mode: cache miss age mode=10040ms, width=10040ms
[2022-05-01 16:51:44.087344][00:00:33.399767][RIGCTRL:trace] ../../src/src/rig.c(2196) trace
[2022-05-01 16:51:44.087344][00:00:33.399773][RIGCTRL:debug] 8:newcat.c(1340):newcat_get_mode entered
[2022-05-01 16:51:44.087344][00:00:33.399777][RIGCTRL:trace] newcat_valid_command MD
[2022-05-01 16:51:44.087344][00:00:33.399784][RIGCTRL:debug] 9:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.087344][00:00:33.399787][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.087344][00:00:33.399791][RIGCTRL:debug] 9:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.087344][00:00:33.399797][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.087344][00:00:33.399802][RIGCTRL:debug] 9:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:44.087344][00:00:33.399806][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOA
[2022-05-01 16:51:44.087344][00:00:33.399812][RIGCTRL:debug] 9:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:44.087344][00:00:33.399816][RIGCTRL:trace] newcat_get_mode: cmd_str = MD0;
[2022-05-01 16:51:44.087344][00:00:33.399822][RIGCTRL:debug] 9:newcat.c(10101):newcat_get_cmd entered


Best regards,
Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Sunday, May 1, 2022 7:47 AM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Can I get more of log so I can see where it's being called?

Not quite enough history there.

Mike W9MDB








On Sunday, May 1, 2022, 12:22:52 AM CDT, SteveO via groups.io <sollmann22@...> wrote:





Mike,

  Okay, so I did get the log file and although the command is never sent to the radio, I do see it in the log.

  I will attach log, but just in case it will not post, I’ll paste the snippet here.

[2022-04-30 21:39:39.386775][00:03:12.237178][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-04-30 21:39:39.386923][00:03:12.237236][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-04-30 21:39:39.386923][00:03:12.237279][RIGCTRL:trace] newcat_valid_command VS
[2022-04-30 21:39:39.386923][00:03:12.237328][RIGCTRL:debug] 13:newcat.c(9957):newcat_get_rigid entered
[2022-04-30 21:39:39.386923][00:03:12.237368][RIGCTRL:trace] rig_id = 670
[2022-04-30 21:39:39.386923][00:03:12.237427][RIGCTRL:debug] 13:newcat.c(9978):newcat_get_rigid returning(670)
[2022-04-30 21:39:39.386923][00:03:12.237483][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
 
This happens a few times in the file, but the COM port never sees the command now.

Best regards,
    Steve  KC5NK

PS:  Do you ever sleep or take a day off?  Thanks for your very quick responses!

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:37 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Yes...band change was using VS even if the rig didn't have it....so only rigs that have it will execute that command now.

Mike W9MDB








On Saturday, April 30, 2022, 04:35:08 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Michael,

  I did see your e-mails after my response.

  I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

  Prior to changing the DLL, it always happened on a band change from say 40m to 20m.  I had confirmed that after submitting issue.

  Bear with me, I will keep trying various changes to see if I can trigger it.  If not, we may chalk it up to some changes in the DLL?

Best regards,
    Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.


SteveO - KC5NK
 

Mike,

Okay, so without the VS commands, using the new DLL you sent, there is a problem.

When the Split is configured for rig, when changing bands from WSJT-x, VFO A is changing but VFO B stays on the previous band.

Then when Enable button is active the radio transmits on VFO-B which isn’t the same band as selected. I my case, I am listening on VFO A (14.095600 MHz) however when I transmit, I am transmitting on VFO B (expected) but it is transmitting on 7.038600 MHz.

Without the VS logic, the FT-991a (or FT-891) are not getting the proper VFO-B settings when changing bands.

I am assuming that the mail system is stripping the log files when I attach them.

Best regards,
Steve KC5NK

Sent from Mail for Windows

From: SteveO via groups.io
Sent: Sunday, May 1, 2022 11:54 AM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Mike,

Here is a larger snippet…

[2022-05-01 16:51:43.276230][00:00:32.589509][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589515][RIGCTRL:debug] rig_set_split_vfo: final rxvfo=VFOA, txvfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589521][RIGCTRL:trace] ../../src/src/rig.c(4541) trace
[2022-05-01 16:51:43.276230][00:00:32.589528][RIGCTRL:debug] rig_set_split_vfo(4560): split already set...ignoring
[2022-05-01 16:51:43.276230][00:00:32.589536][RIGCTRL:debug] 4:rig.c(4561):rig_set_split_vfo returning(0)
[2022-05-01 16:51:43.276230][00:00:32.589541][RIGCTRL:trace] state: true
[2022-05-01 16:51:43.276230][00:00:32.589545][RIGCTRL:trace] rig_set_split_freq_mode freq=7074000 mode = PKTUSB
[2022-05-01 16:51:43.276230][00:00:32.589555][RIGCTRL:debug] 4:rig.c(4314):rig_set_split_freq_mode entered
[2022-05-01 16:51:43.276230][00:00:32.589564][RIGCTRL:trace] vfo_fixup:(from rig_set_split_freq_mode:4336) vfo=TX, vfo_curr=VFOA, split=1
[2022-05-01 16:51:43.276230][00:00:32.589571][RIGCTRL:debug] vfo_fixup(1936): split=1, vfo==TX tx_vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589580][RIGCTRL:trace] vfo_fixup: RIG_VFO_TX changed to VFOB, split=1, satmode=0
[2022-05-01 16:51:43.276230][00:00:32.589594][RIGCTRL:debug] rig_set_split_freq_mode: vfo=VFOB, tx_freq=7074000, tx_mode=PKTUSB, tx_width=-1
[2022-05-01 16:51:43.276230][00:00:32.589600][RIGCTRL:trace] ../../src/src/rig.c(4382) trace
[2022-05-01 16:51:43.276230][00:00:32.589611][RIGCTRL:debug] rig_set_split_freq called vfo=VFOB, curr_vfo=VFOA, tx_freq=7074000
[2022-05-01 16:51:43.276230][00:00:32.589618][RIGCTRL:debug] rig_get_freq(1774) called vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589633][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:1780) vfo=VFOB, vfo_curr=VFOA, split=1
[2022-05-01 16:51:43.276230][00:00:32.589638][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2022-05-01 16:51:43.276230][00:00:32.589645][RIGCTRL:debug] ../../src/src/rig.c(1783) vfo=VFOB, curr_vfo=VFOA
[2022-05-01 16:51:43.277261][00:00:32.589671][RIGCTRL:trace] rig_get_freq: VFOB cache hit age=287ms, freq=14074000
[2022-05-01 16:51:43.277261][00:00:32.589678][RIGCTRL:trace] 4:rig_get_freq: elapsed=1ms
[2022-05-01 16:51:43.277261][00:00:32.589684][RIGCTRL:trace] ../../src/src/rig.c(3705) trace
[2022-05-01 16:51:43.277261][00:00:32.589689][RIGCTRL:debug] ft991_set_split_freq called
[2022-05-01 16:51:43.277261][00:00:32.589694][RIGCTRL:debug] ft991_get_tx_split called
[2022-05-01 16:51:43.277261][00:00:32.589701][RIGCTRL:debug] 5:newcat.c(7454):newcat_get_tx_vfo entered
[2022-05-01 16:51:43.277261][00:00:32.589708][RIGCTRL:trace] newcat_valid_command FT
[2022-05-01 16:51:43.277261][00:00:32.589716][RIGCTRL:debug] 6:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.277261][00:00:32.589729][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.277261][00:00:32.589737][RIGCTRL:debug] 6:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.277261][00:00:32.589745][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.277261][00:00:32.589752][RIGCTRL:debug] 6:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:43.277261][00:00:32.589760][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.277261][00:00:32.589766][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.288397][00:00:32.600968][RIGCTRL:trace] cmd_str = FT;
[2022-05-01 16:51:43.288397][00:00:32.601227][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:43.288397][00:00:32.601244][RIGCTRL:trace] 0000 46 54 3b FT;
[2022-05-01 16:51:43.314230][00:00:32.626751][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.315230][00:00:32.628142][RIGCTRL:trace] read_string_generic(): RX 4 characters, direct=1
[2022-05-01 16:51:43.315230][00:00:32.628158][RIGCTRL:trace] 0000 46 54 30 3b FT0;
[2022-05-01 16:51:43.315230][00:00:32.628165][RIGCTRL:trace] newcat_get_cmd: read count = 4, ret_data = FT0;
[2022-05-01 16:51:43.315230][00:00:32.628173][RIGCTRL:debug] 6:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:43.315230][00:00:32.628178][RIGCTRL:debug] 6:newcat.c(9995):newcat_get_vfo_mode entered
[2022-05-01 16:51:43.315230][00:00:32.628182][RIGCTRL:trace] newcat_valid_command IF
[2022-05-01 16:51:43.315230][00:00:32.628188][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.315230][00:00:32.628192][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.315230][00:00:32.628198][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.315230][00:00:32.628203][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.315230][00:00:32.628209][RIGCTRL:debug] 7:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:43.315230][00:00:32.628221][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.315230][00:00:32.628227][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.326260][00:00:32.638944][RIGCTRL:trace] cmd_str = IF;
[2022-05-01 16:51:43.326260][00:00:32.639263][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:43.326260][00:00:32.639279][RIGCTRL:trace] 0000 49 46 3b IF;
[2022-05-01 16:51:43.352539][00:00:32.665056][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.358186][00:00:32.670815][RIGCTRL:debug] read_string_generic: minlen now 28
[2022-05-01 16:51:43.358186][00:00:32.670837][RIGCTRL:trace] read_string_generic(): RX 28 characters, direct=1
[2022-05-01 16:51:43.358186][00:00:32.670846][RIGCTRL:trace] 0000 49 46 31 32 37 30 30 37 30 37 34 30 30 30 2b 30 IF127007074000+0
[2022-05-01 16:51:43.358186][00:00:32.670853][RIGCTRL:trace] 0010 30 30 30 30 30 43 30 30 30 30 30 3b 00000C00000;
[2022-05-01 16:51:43.358186][00:00:32.670860][RIGCTRL:trace] newcat_get_cmd: read count = 28, ret_data = IF127007074000+000000C00000;
[2022-05-01 16:51:43.358186][00:00:32.670870][RIGCTRL:debug] 7:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670878][RIGCTRL:trace] newcat_get_vfo_mode: offset=22, width_frequency=9
[2022-05-01 16:51:43.358186][00:00:32.670883][RIGCTRL:trace] newcat_get_vfo_mode: vfo mode = VFO
[2022-05-01 16:51:43.358186][00:00:32.670901][RIGCTRL:debug] 6:newcat.c(10050):newcat_get_vfo_mode returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670906][RIGCTRL:trace] newcat_get_tx_vfo: tx_vfo = VFOA
[2022-05-01 16:51:43.358186][00:00:32.670913][RIGCTRL:debug] 5:newcat.c(7515):newcat_get_tx_vfo returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670918][RIGCTRL:debug] 5:newcat.c(7366):newcat_set_tx_vfo entered
[2022-05-01 16:51:43.358186][00:00:32.670925][RIGCTRL:trace] newcat_valid_command FT
[2022-05-01 16:51:43.358186][00:00:32.670930][RIGCTRL:debug] 6:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.358186][00:00:32.670934][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.358186][00:00:32.670941][RIGCTRL:debug] 6:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.358186][00:00:32.670951][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.358186][00:00:32.670962][RIGCTRL:debug] 6:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:43.358186][00:00:32.670970][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOB
[2022-05-01 16:51:43.358186][00:00:32.670979][RIGCTRL:debug] 6:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:43.358186][00:00:32.670988][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(7421) trace
[2022-05-01 16:51:43.358186][00:00:32.670994][RIGCTRL:trace] cmd_str = FT3;, vfo=VFOB
[2022-05-01 16:51:43.358186][00:00:32.671000][RIGCTRL:debug] 6:newcat.c(10502):newcat_set_cmd entered
[2022-05-01 16:51:43.358186][00:00:32.671009][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.358186][00:00:32.671016][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.371189][00:00:32.683726][RIGCTRL:trace] 0000 46 42 30 31 34 30 37 34 30 30 30 3b FB014074000;
[2022-05-01 16:51:43.371189][00:00:32.683755][RIGCTRL:trace] cmd_str = FT3;
[2022-05-01 16:51:43.371189][00:00:32.683763][RIGCTRL:debug] 7:newcat.c(10338):newcat_set_cmd_validate entered
[2022-05-01 16:51:43.371189][00:00:32.683767][RIGCTRL:trace] newcat_set_cmd_validate: priv->cmd_str=FT3;
[2022-05-01 16:51:43.371189][00:00:32.683773][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.371189][00:00:32.683779][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.381920][00:00:32.694544][RIGCTRL:trace] write_block(): TX 7 bytes, method=2
[2022-05-01 16:51:43.381920][00:00:32.694565][RIGCTRL:trace] 0000 46 54 33 3b 46 54 3b FT3;FT;
[2022-05-01 16:51:43.407225][00:00:32.719636][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.409235][00:00:32.722203][RIGCTRL:trace] read_string_generic(): RX 4 characters, direct=1
[2022-05-01 16:51:43.409235][00:00:32.722227][RIGCTRL:trace] 0000 46 54 31 3b FT1;
[2022-05-01 16:51:43.409235][00:00:32.722233][RIGCTRL:trace] newcat_set_cmd: cmd_validate OK
[2022-05-01 16:51:43.409235][00:00:32.722240][RIGCTRL:debug] 7:newcat.c(10519):newcat_set_cmd returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722246][RIGCTRL:debug] 6:newcat.c(7439):newcat_set_tx_vfo returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722252][RIGCTRL:debug] 6:newcat.c(744):newcat_set_freq entered
[2022-05-01 16:51:43.409235][00:00:32.722261][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:43.409235][00:00:32.722267][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722272][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722277][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722296][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722300][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:43.409235][00:00:32.722305][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722311][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722315][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722320][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722325][RIGCTRL:trace] newcat_set_freq: passed vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722345][RIGCTRL:trace] newcat_set_freq: passed freq = 7074000 Hz
[2022-05-01 16:51:43.409235][00:00:32.722354][RIGCTRL:debug] 7:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:43.409235][00:00:32.722359][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722366][RIGCTRL:debug] 7:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722374][RIGCTRL:trace] newcat_set_freq: special_60m=0, 60m freq=0, is_ftdx3000=0,is_ftdx3000dm=0
[2022-05-01 16:51:43.409235][00:00:32.722382][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(904)newcat_set_freq: rig->state.current_vfo=VFOA
[2022-05-01 16:51:43.409235][00:00:32.722390][RIGCTRL:debug] rig_get_freq(1774) called vfo=VFOB
[2022-05-01 16:51:43.409235][00:00:32.722404][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:1780) vfo=VFOB, vfo_curr=VFOA, split=0
[2022-05-01 16:51:43.409235][00:00:32.722413][RIGCTRL:trace] vfo_fixup: final vfo=VFOB
[2022-05-01 16:51:43.409235][00:00:32.722417][RIGCTRL:debug] ../../src/src/rig.c(1783) vfo=VFOB, curr_vfo=VFOA
[2022-05-01 16:51:43.409235][00:00:32.722436][RIGCTRL:trace] rig_get_freq: cache miss age=10000ms, cached_vfo=VFOB, asked_vfo=VFOB
[2022-05-01 16:51:43.409235][00:00:32.722442][RIGCTRL:debug] rig_get_freq(1865): vfo_opt=0, model=1035
[2022-05-01 16:51:43.409235][00:00:32.722447][RIGCTRL:debug] 7:newcat.c(1169):newcat_get_freq entered
[2022-05-01 16:51:43.409235][00:00:32.722451][RIGCTRL:trace] newcat_get_freq: passed vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722455][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:43.409235][00:00:32.722461][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722466][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722471][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722476][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722480][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:43.409235][00:00:32.722485][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722490][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722495][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722500][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722505][RIGCTRL:debug] 8:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:43.409235][00:00:32.722509][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOB
[2022-05-01 16:51:43.409235][00:00:32.722514][RIGCTRL:debug] 8:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:43.409235][00:00:32.722518][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:43.409235][00:00:32.722524][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.409235][00:00:32.722527][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.409235][00:00:32.722533][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.409235][00:00:32.722538][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.409235][00:00:32.722542][RIGCTRL:trace] cmd_str = FB;
[2022-05-01 16:51:43.409235][00:00:32.722548][RIGCTRL:debug] 8:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:43.409235][00:00:32.722555][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.409235][00:00:32.722560][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.421259][00:00:32.733763][RIGCTRL:trace] 0000 46 54 31 3b FT1;
[2022-05-01 16:51:43.421259][00:00:32.733780][RIGCTRL:trace] cmd_str = FB;
[2022-05-01 16:51:43.421259][00:00:32.733908][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:43.421259][00:00:32.733922][RIGCTRL:trace] 0000 46 42 3b FB;
[2022-05-01 16:51:43.447149][00:00:32.759548][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:43.450150][00:00:32.762992][RIGCTRL:debug] read_string_generic: minlen now 12
[2022-05-01 16:51:43.450150][00:00:32.763005][RIGCTRL:trace] read_string_generic(): RX 12 characters, direct=1
[2022-05-01 16:51:43.450150][00:00:32.763012][RIGCTRL:trace] 0000 46 42 30 31 34 30 37 34 30 30 30 3b FB014074000;
[2022-05-01 16:51:43.450150][00:00:32.763018][RIGCTRL:trace] newcat_get_cmd: read count = 12, ret_data = FB014074000;
[2022-05-01 16:51:43.450150][00:00:32.763031][RIGCTRL:debug] 8:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:43.450150][00:00:32.763048][RIGCTRL:trace] newcat_get_freq: freq = 14074000 Hz for vfo VFOB
[2022-05-01 16:51:43.450150][00:00:32.763055][RIGCTRL:debug] 7:newcat.c(1234):newcat_get_freq returning(0)
[2022-05-01 16:51:43.450150][00:00:32.763084][RIGCTRL:trace] 6:rig_get_freq: elapsed=41ms
[2022-05-01 16:51:43.450150][00:00:32.763091][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(923)newcat_set_freq: checking VFOB for band change
[2022-05-01 16:51:43.450150][00:00:32.763102][RIGCTRL:trace] newcat_band_index: freq=7.074e+06, band=3
[2022-05-01 16:51:43.450150][00:00:32.763111][RIGCTRL:trace] newcat_band_index: freq=1.4074e+07, band=5
[2022-05-01 16:51:43.450150][00:00:32.763115][RIGCTRL:trace] newcat_set_freq: VFO_B band changing=1
[2022-05-01 16:51:43.450150][00:00:32.763121][RIGCTRL:trace] newcat_valid_command BS
[2022-05-01 16:51:43.450150][00:00:32.763126][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.450150][00:00:32.763130][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.450150][00:00:32.763135][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.450150][00:00:32.763143][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:43.450150][00:00:32.763147][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-05-01 16:51:43.450150][00:00:32.763151][RIGCTRL:trace] newcat_valid_command VS
[2022-05-01 16:51:43.450150][00:00:32.763156][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.450150][00:00:32.763161][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.450150][00:00:32.763165][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.450150][00:00:32.763171][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
[2022-05-01 16:51:43.450150][00:00:32.763175][RIGCTRL:debug] newcat.c(7332):newcat_valid_command returning2(0)
[2022-05-01 16:51:43.450150][00:00:32.763180][RIGCTRL:trace] newcat_band_index: freq=7.074e+06, band=3
[2022-05-01 16:51:43.450150][00:00:32.763185][RIGCTRL:debug] 7:newcat.c(10502):newcat_set_cmd entered
[2022-05-01 16:51:43.450150][00:00:32.763191][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.450150][00:00:32.763195][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.461210][00:00:32.773671][RIGCTRL:trace] cmd_str = BS03;
[2022-05-01 16:51:43.461210][00:00:32.773686][RIGCTRL:debug] 8:newcat.c(10338):newcat_set_cmd_validate entered
[2022-05-01 16:51:43.461210][00:00:32.773690][RIGCTRL:trace] newcat_set_cmd_validate: priv->cmd_str=BS03;
[2022-05-01 16:51:43.461210][00:00:32.773695][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.461210][00:00:32.773700][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:43.472225][00:00:32.784819][RIGCTRL:trace] write_block(): TX 5 bytes, method=2
[2022-05-01 16:51:43.472225][00:00:32.784837][RIGCTRL:trace] 0000 42 53 30 33 3b BS03;
[2022-05-01 16:51:43.498210][00:00:32.810604][RIGCTRL:debug] 8:newcat.c(10437):newcat_set_cmd_validate returning(0)
[2022-05-01 16:51:43.498210][00:00:32.810643][RIGCTRL:trace] newcat_set_cmd: cmd_validate OK
[2022-05-01 16:51:43.498210][00:00:32.810657][RIGCTRL:debug] 7:newcat.c(10519):newcat_set_cmd returning(0)
[2022-05-01 16:51:43.998686][00:00:33.311173][RIGCTRL:trace] newcat_valid_command VS
[2022-05-01 16:51:43.998686][00:00:33.311217][RIGCTRL:debug] 7:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:43.998686][00:00:33.311226][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:43.998686][00:00:33.311236][RIGCTRL:debug] 7:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:43.998686][00:00:33.311246][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
[2022-05-01 16:51:43.998686][00:00:33.311257][RIGCTRL:debug] newcat.c(7332):newcat_valid_command returning2(0)
[2022-05-01 16:51:43.998686][00:00:33.311271][RIGCTRL:debug] 7:newcat.c(10502):newcat_set_cmd entered
[2022-05-01 16:51:43.998686][00:00:33.311279][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:43.998686][00:00:33.311290][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:44.009637][00:00:33.322216][RIGCTRL:trace] cmd_str = BS03;
[2022-05-01 16:51:44.009637][00:00:33.322243][RIGCTRL:debug] 8:newcat.c(10338):newcat_set_cmd_validate entered
[2022-05-01 16:51:44.009637][00:00:33.322249][RIGCTRL:trace] newcat_set_cmd_validate: priv->cmd_str=BS03;
[2022-05-01 16:51:44.009637][00:00:33.322255][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:44.009637][00:00:33.322260][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:44.020537][00:00:33.333535][RIGCTRL:trace] write_block(): TX 5 bytes, method=2
[2022-05-01 16:51:44.020537][00:00:33.333561][RIGCTRL:trace] 0000 42 53 30 33 3b BS03;
[2022-05-01 16:51:44.046545][00:00:33.358934][RIGCTRL:debug] 8:newcat.c(10437):newcat_set_cmd_validate returning(0)
[2022-05-01 16:51:44.046545][00:00:33.358955][RIGCTRL:trace] newcat_set_cmd: cmd_validate OK
[2022-05-01 16:51:44.046545][00:00:33.358962][RIGCTRL:debug] 7:newcat.c(10519):newcat_set_cmd returning(0)
[2022-05-01 16:51:44.046545][00:00:33.358988][RIGCTRL:debug] rig_get_freq(1774) called vfo=Main
[2022-05-01 16:51:44.046545][00:00:33.359002][RIGCTRL:trace] vfo_fixup:(from rig_get_freq:1780) vfo=Main, vfo_curr=VFOA, split=0
[2022-05-01 16:51:44.046545][00:00:33.359008][RIGCTRL:debug] ../../src/src/rig.c(1783) vfo=VFOA, curr_vfo=VFOA
[2022-05-01 16:51:44.046545][00:00:33.359027][RIGCTRL:trace] rig_get_freq: cache miss age=10000ms, cached_vfo=VFOA, asked_vfo=VFOA
[2022-05-01 16:51:44.046545][00:00:33.359031][RIGCTRL:debug] rig_get_freq(1865): vfo_opt=0, model=1035
[2022-05-01 16:51:44.046545][00:00:33.359037][RIGCTRL:debug] 7:newcat.c(1169):newcat_get_freq entered
[2022-05-01 16:51:44.046545][00:00:33.359040][RIGCTRL:trace] newcat_get_freq: passed vfo = VFOA
[2022-05-01 16:51:44.046545][00:00:33.359044][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:44.046545][00:00:33.359053][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.046545][00:00:33.359056][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.046545][00:00:33.359060][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.046545][00:00:33.359065][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.046545][00:00:33.359068][RIGCTRL:trace] newcat_valid_command FB
[2022-05-01 16:51:44.046545][00:00:33.359078][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.046545][00:00:33.359081][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.046545][00:00:33.359086][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.046545][00:00:33.359090][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.046545][00:00:33.359095][RIGCTRL:debug] 8:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:44.046545][00:00:33.359098][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOA
[2022-05-01 16:51:44.046545][00:00:33.359102][RIGCTRL:debug] 8:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:44.046545][00:00:33.359105][RIGCTRL:trace] newcat_valid_command FA
[2022-05-01 16:51:44.046545][00:00:33.359109][RIGCTRL:debug] 8:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.046545][00:00:33.359112][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.046545][00:00:33.359118][RIGCTRL:debug] 8:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.046545][00:00:33.359122][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.046545][00:00:33.359126][RIGCTRL:trace] cmd_str = FA;
[2022-05-01 16:51:44.046545][00:00:33.359131][RIGCTRL:debug] 8:newcat.c(10101):newcat_get_cmd entered
[2022-05-01 16:51:44.046545][00:00:33.359137][RIGCTRL:trace] rig_flush: called for serial device
[2022-05-01 16:51:44.046545][00:00:33.359142][RIGCTRL:trace] read_string_generic called, rxmax=4095 direct=1
[2022-05-01 16:51:44.058747][00:00:33.371373][RIGCTRL:trace] 0000 53 48 30 31 30 3b SH010;
[2022-05-01 16:51:44.058747][00:00:33.371444][RIGCTRL:trace] cmd_str = FA;
[2022-05-01 16:51:44.058747][00:00:33.371778][RIGCTRL:trace] write_block(): TX 3 bytes, method=2
[2022-05-01 16:51:44.058747][00:00:33.371791][RIGCTRL:trace] 0000 46 41 3b FA;
[2022-05-01 16:51:44.085345][00:00:33.397670][RIGCTRL:trace] read_string_generic called, rxmax=129 direct=1
[2022-05-01 16:51:44.087344][00:00:33.399644][RIGCTRL:debug] read_string_generic: minlen now 12
[2022-05-01 16:51:44.087344][00:00:33.399653][RIGCTRL:trace] read_string_generic(): RX 12 characters, direct=1
[2022-05-01 16:51:44.087344][00:00:33.399660][RIGCTRL:trace] 0000 46 41 30 30 37 30 37 34 30 30 30 3b FA007074000;
[2022-05-01 16:51:44.087344][00:00:33.399666][RIGCTRL:trace] newcat_get_cmd: read count = 12, ret_data = FA007074000;
[2022-05-01 16:51:44.087344][00:00:33.399674][RIGCTRL:debug] 8:newcat.c(10321):newcat_get_cmd returning(0)
[2022-05-01 16:51:44.087344][00:00:33.399691][RIGCTRL:trace] newcat_get_freq: freq = 7074000 Hz for vfo VFOA
[2022-05-01 16:51:44.087344][00:00:33.399698][RIGCTRL:debug] 7:newcat.c(1234):newcat_get_freq returning(0)
[2022-05-01 16:51:44.087344][00:00:33.399727][RIGCTRL:trace] 6:rig_get_freq: elapsed=41ms
[2022-05-01 16:51:44.087344][00:00:33.399734][RIGCTRL:debug] 7:rig.c(2135):rig_get_mode entered
[2022-05-01 16:51:44.087344][00:00:33.399741][RIGCTRL:trace] vfo_fixup:(from rig_get_mode:2155) vfo=Main, vfo_curr=VFOA, split=0
[2022-05-01 16:51:44.087344][00:00:33.399754][RIGCTRL:trace] rig_get_mode: VFOA cache check age=10040ms
[2022-05-01 16:51:44.087344][00:00:33.399762][RIGCTRL:trace] rig_get_mode: cache miss age mode=10040ms, width=10040ms
[2022-05-01 16:51:44.087344][00:00:33.399767][RIGCTRL:trace] ../../src/src/rig.c(2196) trace
[2022-05-01 16:51:44.087344][00:00:33.399773][RIGCTRL:debug] 8:newcat.c(1340):newcat_get_mode entered
[2022-05-01 16:51:44.087344][00:00:33.399777][RIGCTRL:trace] newcat_valid_command MD
[2022-05-01 16:51:44.087344][00:00:33.399784][RIGCTRL:debug] 9:newcat.c(9957):newcat_get_rigid entered
[2022-05-01 16:51:44.087344][00:00:33.399787][RIGCTRL:trace] rig_id = 670
[2022-05-01 16:51:44.087344][00:00:33.399791][RIGCTRL:debug] 9:newcat.c(9978):newcat_get_rigid returning(670)
[2022-05-01 16:51:44.087344][00:00:33.399797][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-05-01 16:51:44.087344][00:00:33.399802][RIGCTRL:debug] 9:newcat.c(7522):newcat_set_vfo_from_alias entered
[2022-05-01 16:51:44.087344][00:00:33.399806][RIGCTRL:trace] newcat_set_vfo_from_alias: alias vfo = VFOA
[2022-05-01 16:51:44.087344][00:00:33.399812][RIGCTRL:debug] 9:newcat.c(7580):newcat_set_vfo_from_alias returning(0)
[2022-05-01 16:51:44.087344][00:00:33.399816][RIGCTRL:trace] newcat_get_mode: cmd_str = MD0;
[2022-05-01 16:51:44.087344][00:00:33.399822][RIGCTRL:debug] 9:newcat.c(10101):newcat_get_cmd entered


Best regards,
Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Sunday, May 1, 2022 7:47 AM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Can I get more of log so I can see where it's being called?

Not quite enough history there.

Mike W9MDB








On Sunday, May 1, 2022, 12:22:52 AM CDT, SteveO via groups.io <sollmann22@...> wrote:





Mike,

  Okay, so I did get the log file and although the command is never sent to the radio, I do see it in the log.

  I will attach log, but just in case it will not post, I’ll paste the snippet here.

[2022-04-30 21:39:39.386775][00:03:12.237178][RIGCTRL:debug] newcat.c(7290):newcat_valid_command returning2(1)
[2022-04-30 21:39:39.386923][00:03:12.237236][RIGCTRL:trace] ../../../src/rigs/yaesu/newcat.c(943) trace
[2022-04-30 21:39:39.386923][00:03:12.237279][RIGCTRL:trace] newcat_valid_command VS
[2022-04-30 21:39:39.386923][00:03:12.237328][RIGCTRL:debug] 13:newcat.c(9957):newcat_get_rigid entered
[2022-04-30 21:39:39.386923][00:03:12.237368][RIGCTRL:trace] rig_id = 670
[2022-04-30 21:39:39.386923][00:03:12.237427][RIGCTRL:debug] 13:newcat.c(9978):newcat_get_rigid returning(670)
[2022-04-30 21:39:39.386923][00:03:12.237483][RIGCTRL:trace] newcat_valid_command: 'FT-991' command 'VS' not supported
 
This happens a few times in the file, but the COM port never sees the command now.

Best regards,
    Steve  KC5NK

PS:  Do you ever sleep or take a day off?  Thanks for your very quick responses!

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:37 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Yes...band change was using VS even if the rig didn't have it....so only rigs that have it will execute that command now.

Mike W9MDB








On Saturday, April 30, 2022, 04:35:08 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





Michael,

  I did see your e-mails after my response.

  I’m currently testing, trying to duplicate the issue, but with the new Ham Lib DLL (10 MB larger than previous) I cannot duplicate the issue.

  Prior to changing the DLL, it always happened on a band change from say 40m to 20m.  I had confirmed that after submitting issue.

  Bear with me, I will keep trying various changes to see if I can trigger it.  If not, we may chalk it up to some changes in the DLL?

Best regards,
    Steve KC5NK

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 4:26 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

Don't know if you saw my email to try this DLL

https://www.dropbox.com/s/7aejdv74nfjxr0q/libhamlib-4.dll?dl=0

WSJT-X does expect responses to some commands but VS isn't one that's required.  That's the hamlib backend doing that.

Mike W9MDB




On Saturday, April 30, 2022, 04:00:14 PM CDT, SteveO via groups.io <sollmann22@...> wrote:





It just would generate an error.  But no problem.

Didn’t know if anyone was aware of it.

I wrote my own application that is a proxy between WSJT-X and the radio.  My application allows me to control the radio, while not interfering with WSJT-x.    I created a function “isWsjtxSafe()” and only allow commands through that are recognized and responses.  VS and MD commands are not expected commands for the FT-891/FT-991a.

Was under impression WSJT-x would only send commands to radios that they understood.  If it doesn’t bother you, it doesn’t bother me.  I just send TX0; to the radio every time an unexpected/undefined command is sent to the radio.  Sort of a failsafe ….  Had bad experience where radio was in a TX state unexpectedly and stayed on for an undesirable period of time.

Is there a certain behavior expected for unsupported commands to the radios that WSJT-x is looking for?

Best regards,
  Steve (KC5NK)

Sent from Mail for Windows

From: Michael Black via groups.io
Sent: Saturday, April 30, 2022 3:13 PM
To: main@WSJTX.groups.io
Subject: Re: [WSJTX] WSJT-X sending incorrect command to FT-991a #Cat_RigControl

And is it causing a problem?  Other than you seeing the unexpected command?

Mike W9MDB
    On Saturday, April 30, 2022, 03:03:35 PM CDT, SteveO via groups.io <sollmann22@...> wrote: 

Previously had issue with MD command over CAT.  Upgraded Ham Lib DLL file as instructed and that worked.

With the new DLL, fixed the MD command but now am seeing commands occasionally:  VS1;, VS0; and VS; commands over the serial port.  The radio does not recognize these commands. 

From my logs:
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS1;
20220430_19:46:02.142 Z  ** SYS  | EXT CAT, unexpected command:  VS;
20220430_19:46:04.854 Z  ** SYS  | EXT CAT, unexpected command:  VS0;
20220430_19:46:04.855 Z  ** SYS  | EXT CAT, unexpected command:  VS;

This family of commands is not listed in the CAT manual from Yaesu for the FT-991a.  I checked in FT891 CAT manual, also not there, but it was in the FT-950 CAT manual as VFO select.

I know I was trying to establish FT8 contact not sure if I changed bands from 40m to 20m around this time or changing from one FT8 station to another when this occurred.