Timed out while waiting for message #194

janjezik · 2023-02-05T15:53:33Z

Hi.
Thanks for sharing antfs-cli with the world.
I have been using it for years on old debian with no issues. Recently i switched to Ubuntu 22.04.1 and I am now unable to make it work. I have read all the relevant issues related to "timeout" in antfs-cli as well as in openant. I have tried everything I was able to understand:

I have tried it after removing the authfile from ~/.config/antfs-cli/xxxxxxxxxx/
I have also tried to remove the whole ~/.config/antfs-cli/ directory
I have tried the old version of antfs-cli and openant I had been using on debian
I have tried the most recent versions of antfs-cli and openant
I checked if the ANT dongle is recognized - it is: Bus 003 Device 002: ID 0fcf:1009 Dynastream Innovations, Inc. ANTUSB-m Stick

After I run antfs-cli I only get
Request basic information...

After couple of seconds it fails with
ant.easy.exception.AntException: Timed out while waiting for message

The ANT probably doesn't even start any communication with my Garmin Swim watch, because it doesn't show anything on the display.

Debug log: 20230205-162321-antfs-cli-log
Terminal output: https://gist.github.com/janjezik/0873c673389395c45b01e8dcca180823#file-terminal-output
I am using Python 3.10

I appreciate any suggestion how to debug this issue.
Thanks

The text was updated successfully, but these errors were encountered:

gjaekel · 2023-02-05T17:45:55Z

I have tried it after removing the authfile from ~/.config/antfs-cli/xxxxxxxxxx/

I have also tried to remove the whole ~/.config/antfs-cli/ directory

Did you also try to re-pair (after enabling pairing on the watch)?

Do you need a log of a working transfer to compare? I may provide one.

BTW: Should make no big deal because it have worked before, but just for interest: What model of watch?

janjezik · 2023-02-05T21:25:46Z

Hi.
The watch is Garmin Swim.
Trying to pair fails as well:
Terminal output

Debug log from the pairing attempt:
20230205-222343-antfs-cli.log

janjezik · 2023-02-12T12:53:12Z

Hi.
I am still unable to make it work.

Do you need a log of a working transfer to compare? I may provide one.

Could you please provide me with the debug log from successful transfer?

Thank you.

gjaekel · 2023-02-12T14:26:18Z

I would like to send it private to you, may contact me via email?

alelova · 2023-03-26T17:55:00Z

Hello.
Same issue after update ubuntu to 22.04.

gotenp · 2023-03-29T20:13:03Z

I have the same issue since I upgraded to linux mint 21.1 from 20.3
Is there a way to get antfs-cli working again.
I noticed that openant scan seems to be working
This is the output:
openant scan Starting scanner for #0, type 0, press Ctrl-C to finish Found new device #37494 DeviceType.Unknown; device_type: 1, transmission_type: 5
But using antfs-cli gives
antfs-cli Request basic information... Traceback (most recent call last): File "/usr/local/lib/python3.10/dist-packages/antfs_cli-0.4-py3.10.egg/antfs_cli/program.py", line 382, in main g = AntFSCLI(config_dir, args) File "/usr/local/lib/python3.10/dist-packages/antfs_cli-0.4-py3.10.egg/antfs_cli/program.py", line 140, in __init__ Application.__init__(self) File "/home/peter/.local/lib/python3.10/site-packages/ant/fs/manager.py", line 141, in __init__ raise e File "/home/peter/.local/lib/python3.10/site-packages/ant/fs/manager.py", line 115, in __init__ m = self._node.request_message(Message.ID.RESPONSE_CAPABILITIES) File "/home/peter/.local/lib/python3.10/site-packages/ant/easy/node.py", line 116, in request_message return self.wait_for_special(messageId) File "/home/peter/.local/lib/python3.10/site-packages/ant/easy/node.py", line 144, in wait_for_special return wait_for_special(event_id, self._responses, self._responses_cond) File "/home/peter/.local/lib/python3.10/site-packages/ant/easy/filter.py", line 111, in wait_for_special return wait_for_message(match, process, queue, condition) File "/home/peter/.local/lib/python3.10/site-packages/ant/easy/filter.py", line 62, in wait_for_message raise AntException("Timed out while waiting for message") ant.easy.exception.AntException: Timed out while waiting for message Interrupted: Timed out while waiting for message
if I use --debug I get this info...
`Drivers available: [<class 'ant.base.driver.SerialDriver'>, <class 'ant.base.driver.USB2Driver'>, <class 'ant.base.driver.USB3Driver'>]
Using driver: <class 'ant.base.driver.USB2Driver'>
USB Find device, vendor 0xfcf, product 0x1008
USB Config values:
Config 1
Interface 0, Alt 0
Endpoint 129
Endpoint 1
A kernel driver active, detatching
USB Endpoint out: ENDPOINT 0x1: Bulk OUT ===============================
bLength : 0x7 (7 bytes)
bDescriptorType : 0x5 Endpoint
bEndpointAddress : 0x1 OUT
bmAttributes : 0x2 Bulk
wMaxPacketSize : 0x40 (64 bytes)
bInterval : 0x1, 1
USB Endpoint in: ENDPOINT 0x81: Bulk IN ===============================
bLength : 0x7 (7 bytes)
bDescriptorType : 0x5 Endpoint
bEndpointAddress : 0x81 IN
bmAttributes : 0x2 Bulk
wMaxPacketSize : 0x40 (64 bytes)
bInterval : 0x1, 129
Ant runner started
Write data: [a4 01 4a 00 ef]
Read data: [a4 01 6f 20 ea] (now have [a4 01 6f 20 ea] in buffer)
Got response start-up, <ant.base.Message 6f:[20] (s:a4, l:1, c:ea)>
Timeout waiting for message: (110, 'Operation timed out')
Write data: [a4 02 4d 00 54 bf]
Read data: [a4 06 54 08 03 00 ba 36 00 71] (now have [a4 06 54 08 03 00 ba 36 00 71] in buffer)
Write data: [a4 09 46 00 a8 a4 23 b9 f5 5e 63 c1 74]
Write data: [a4 02 4d 00 61 8a]
Got response general, <ant.base.Message 54:[08 03 00 ba 36 00] (s:a4, l:6, c:71)>
wait for message matching <function wait_for_response..match at 0x7f8db5fa5090>
Write data: [a4 02 4d 00 3e d5]
looking for matching message in deque([])
Read data: [a4 03 40 00 46 00 a1] (now have [a4 03 40 00 46 00 a1] in buffer)
_worker_response None, 111, array('B', [32])

could not find response matching <function wait_for_response..match at 0x7f8db5fa5090>
Got channel response other, <ant.base.Message 40:[00 46 00] (s:a4, l:3, c:a1)>
_worker_response None, 84, array('B', [8, 3, 0, 186, 54, 0])
capabilities max_channels: 8, max_networks 3, standard_options: set(), advanced_options: {<AdvancedOptions.Reserved: 8>, <AdvancedOptions.SerialNumberEnabled: 3>, <AdvancedOptions.LowPrioritySearchNEnabled: 5>, <AdvancedOptions.SearchListEnabled: 7>, <AdvancedOptions.PerChannelTxPowerEnabled: 4>}; {<AdvancedOptionsTwo.ExtMessageEnabled: 1>, <AdvancedOptionsTwo.ExtAssignEnabled: 5>, <AdvancedOptionsTwo.ScanModeEnabled: 2>, <AdvancedOptionsTwo.ProximitySearchEnabled: 4>}
_worker_response 0, 70, array('B', [0])
looking for matching message in deque([(None, 111, array('B', [32])), (0, 70, array('B', [0]))])
response found (0, 70, array('B', [0]))
Request basic information...
requesting message 0x54
Read data: [a4 04 61 02 39 30 00 ca a4 0b 3e 41 4a 4b 31 2e 30 34 52 41 46 00 9f] (now have [a4 04 61 02 39 30 00 ca a4 0b 3e 41 4a 4b 31 2e 30 34 52 41 46 00 9f] in buffer)
Write data: [a4 02 4d 00 54 bf]
Got response general, <ant.base.Message 61:[02 39 30 00] (s:a4, l:4, c:ca)>
done requesting message 0x54
Got response general, <ant.base.Message 3e:[41 4a 4b 31 2e 30 34 52 41 46 00] (s:a4, l:11, c:9f)>
_worker_response None, 97, array('B', [2, 57, 48, 0])
wait for message matching <function wait_for_special..match at 0x7f8db5fa51b0>
Read data: [a4 06 54 08 03 00 ba 36 00 71] (now have [a4 06 54 08 03 00 ba 36 00 71] in buffer)
serial 3160322
looking for matching message in deque([(None, 111, array('B', [32]))])
Got response general, <ant.base.Message 54:[08 03 00 ba 36 00] (s:a4, l:6, c:71)>
_worker_response None, 62, array('B', [65, 74, 75, 49, 46, 48, 52, 82, 65, 70, 0])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
ant_version AJK1.04RAF
_worker_response None, 84, array('B', [8, 3, 0, 186, 54, 0])
capabilities max_channels: 8, max_networks 3, standard_options: set(), advanced_options: {<AdvancedOptions.Reserved: 8>, <AdvancedOptions.SerialNumberEnabled: 3>, <AdvancedOptions.LowPrioritySearchNEnabled: 5>, <AdvancedOptions.SearchListEnabled: 7>, <AdvancedOptions.PerChannelTxPowerEnabled: 4>}; {<AdvancedOptionsTwo.ExtMessageEnabled: 1>, <AdvancedOptionsTwo.ExtAssignEnabled: 5>, <AdvancedOptionsTwo.ScanModeEnabled: 2>, <AdvancedOptionsTwo.ProximitySearchEnabled: 4>}
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
looking for matching message in deque([(None, 111, array('B', [32]))])
could not find response matching <function wait_for_special..match at 0x7f8db5fa51b0>
Timeout waiting for message: (110, 'Operation timed out')
Stoping openant.easy
Stoping openant.base
Timeout waiting for message: (110, 'Operation timed out')
Ant runner stopped
usbdriver.closed
`

gotenp · 2023-03-30T06:54:20Z

I have the same issue since I upgraded to linux mint 21.1 from 20.3 Is there a way to get antfs-cli working again. I noticed that openant scan seems to be working This is the output:

SNIP

Update: I reverted back to mint 20.3 antfs-cli is working again ... Happy days... :-)

prcoder-1 · 2023-06-14T18:53:01Z

Same issue (Timed out while waiting for message) on Fedora release 37 (Thirty Seven).
Before that it worked fine on Fedora 35.

microniko · 2023-07-21T19:57:35Z

I have the same issue with Debian GNU/Linux 11 (bullseye, kernel 5.10.0-23-amd64).

microniko · 2023-07-30T21:33:07Z

I have the same issue with Debian GNU/Linux 12.1 (bookworm, kernel 6.1.0-10-amd64)

(antfs) nicolas@carbone:~/openant-master$ antfs-cli --pair
Request basic information...
Traceback (most recent call last):
  File "/home/nicolas/antfs/lib/python3.11/site-packages/antfs_cli-0.4-py3.11.egg/antfs_cli/program.py", line 382, in main
    g = AntFSCLI(config_dir, args)
        ^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/nicolas/antfs/lib/python3.11/site-packages/antfs_cli-0.4-py3.11.egg/antfs_cli/program.py", line 140, in __init__
    Application.__init__(self)
  File "/home/nicolas/antfs/lib/python3.11/site-packages/ant/fs/manager.py", line 141, in __init__
    raise e
  File "/home/nicolas/antfs/lib/python3.11/site-packages/ant/fs/manager.py", line 115, in __init__
    m = self._node.request_message(Message.ID.RESPONSE_CAPABILITIES)
        ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/nicolas/antfs/lib/python3.11/site-packages/ant/easy/node.py", line 116, in request_message
    return self.wait_for_special(messageId)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/nicolas/antfs/lib/python3.11/site-packages/ant/easy/node.py", line 144, in wait_for_special
    return wait_for_special(event_id, self._responses, self._responses_cond)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/nicolas/antfs/lib/python3.11/site-packages/ant/easy/filter.py", line 111, in wait_for_special
    return wait_for_message(match, process, queue, condition)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/home/nicolas/antfs/lib/python3.11/site-packages/ant/easy/filter.py", line 62, in wait_for_message
    raise AntException("Timed out while waiting for message")
ant.easy.exception.AntException: Timed out while waiting for message
Interrupted: Timed out while waiting for message

arjanboltjes · 2023-08-13T09:47:52Z

First time user here.
Same issue, with an identical output from antfs-cli --pair as @microniko got.
I'm not sure openant scan yields any pairing, like @gotenp posted: how long is this pairing supposed to take? I haven't seen the 'Found new device' myself.

Garmin 310 XT, pairing enabled
Ubuntu 22.04.2 LTS
openant 1.2.1
antfs-cli downloaded just now

microniko · 2023-08-13T19:29:02Z

I tried with Linux Mint 20.3 Una (like @gotenp) in a VirtualBox VM but I had the same error :

vboxuser@linuxmint:~/garmin/antfs-cli-master$ antfs-cli --pair
Request basic information...
Traceback (most recent call last):
  File "/usr/local/lib/python3.8/dist-packages/antfs_cli-0.4-py3.8.egg/antfs_cli/program.py", line 382, in main
    g = AntFSCLI(config_dir, args)
  File "/usr/local/lib/python3.8/dist-packages/antfs_cli-0.4-py3.8.egg/antfs_cli/program.py", line 140, in __init__
    Application.__init__(self)
  File "/usr/local/lib/python3.8/dist-packages/ant/fs/manager.py", line 141, in __init__
    raise e
  File "/usr/local/lib/python3.8/dist-packages/ant/fs/manager.py", line 115, in __init__
    m = self._node.request_message(Message.ID.RESPONSE_CAPABILITIES)
  File "/usr/local/lib/python3.8/dist-packages/ant/easy/node.py", line 116, in request_message
    return self.wait_for_special(messageId)
  File "/usr/local/lib/python3.8/dist-packages/ant/easy/node.py", line 144, in wait_for_special
    return wait_for_special(event_id, self._responses, self._responses_cond)
  File "/usr/local/lib/python3.8/dist-packages/ant/easy/filter.py", line 111, in wait_for_special
    return wait_for_message(match, process, queue, condition)
  File "/usr/local/lib/python3.8/dist-packages/ant/easy/filter.py", line 62, in wait_for_message
    raise AntException("Timed out while waiting for message")
ant.easy.exception.AntException: Timed out while waiting for message
Interrupted: Timed out while waiting for message

I had a Cycplus ANT stick :

Bus 002 Device 004: ID 0fcf:1008 Dynastream Innovations, Inc. ANTUSB2 Stick

Will I try with Fedora 35 like @prcoder-1 ?
edit : same error message...

microniko · 2023-11-05T20:31:27Z

@arjanboltjes and @prcoder-1 : do you finally solved the problem ?

alelova · 2024-02-11T19:06:03Z

cool! it works in Ubuntu 22.04 after the openant update. thanks @tuna-f1sh, i can still use my garmin from 2012.

m:~/varios/openant/openant$ lsb_release -a
No LSB modules are available.
Distributor ID:	Ubuntu
Description:	Ubuntu 22.04.3 LTS
Release:	22.04
Codename:	jammy

m:~/varios/openant/openant$ openant --version
openant 1.3.1

m:~/varios/openant/openant$ antfs-cli
Searching...
Authenticating with Forerunner 310XT (3894335436)
 - Passkey: OK
 - Set time: FAILED
Downloading 0 file(s)

robho mentioned this issue Nov 15, 2023

antfs-cli fails with timeout Tigge/openant#95

Closed

tuna-f1sh mentioned this issue Nov 16, 2023

Fix problem with pyusb 1.0.2 Tigge/openant#93

Closed

robho mentioned this issue Nov 17, 2023

antfs: Skip sending CAPABILITIES request Tigge/openant#97

Merged

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Timed out while waiting for message #194

Timed out while waiting for message #194

janjezik commented Feb 5, 2023

gjaekel commented Feb 5, 2023 •

edited

Loading

janjezik commented Feb 5, 2023

janjezik commented Feb 12, 2023

gjaekel commented Feb 12, 2023

alelova commented Mar 26, 2023

gotenp commented Mar 29, 2023

gotenp commented Mar 30, 2023

prcoder-1 commented Jun 14, 2023

microniko commented Jul 21, 2023

microniko commented Jul 30, 2023

arjanboltjes commented Aug 13, 2023

microniko commented Aug 13, 2023 •

edited

Loading

microniko commented Nov 5, 2023

alelova commented Feb 11, 2024

Timed out while waiting for message #194

Timed out while waiting for message #194

Comments

janjezik commented Feb 5, 2023

gjaekel commented Feb 5, 2023 • edited Loading

janjezik commented Feb 5, 2023

janjezik commented Feb 12, 2023

gjaekel commented Feb 12, 2023

alelova commented Mar 26, 2023

gotenp commented Mar 29, 2023

gotenp commented Mar 30, 2023

prcoder-1 commented Jun 14, 2023

microniko commented Jul 21, 2023

microniko commented Jul 30, 2023

arjanboltjes commented Aug 13, 2023

microniko commented Aug 13, 2023 • edited Loading

microniko commented Nov 5, 2023

alelova commented Feb 11, 2024

gjaekel commented Feb 5, 2023 •

edited

Loading

microniko commented Aug 13, 2023 •

edited

Loading