braiden / python-ant-downloader

Python Ant Downloader - tools for download from garmin wireless (ANT) devices
BSD 2-Clause "Simplified" License
141 stars 44 forks source link

Caught error while communicating with device #11

Open JanneKro opened 12 years ago

JanneKro commented 12 years ago

Trying to connect to my 405cx with Ubuntu 12.04. Installed the packages as described in the installation procedure, packets gotten from Ubuntu-repo. python-ant-downloader from branch.

I had worse luck before connecting my 405cx to windows and upgrading the software (as well as setting the correct time) (batteries been dead for ages).

This the log I get now (ran with 'sudo ant-downloader' as there seems to be a permission issue with the udev-way):

[MainThread] 2012-03-18 19:10:25,335 DEBUG Executing Command. RESET_SYSTEM() [Thread-1] 2012-03-18 19:10:25,340 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=0) [MainThread] 2012-03-18 19:10:25,342 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=84) [Thread-1] 2012-03-18 19:10:25,344 DEBUG Processing reply. CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 19:10:25,346 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=62) [Thread-1] 2012-03-18 19:10:25,349 DEBUG Processing reply. VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 19:10:25,352 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=97) [Thread-1] 2012-03-18 19:10:25,354 DEBUG Processing reply. SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 19:10:25,356 DEBUG Device Capabilities: CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 19:10:25,356 DEBUG Device ANT Version: VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 19:10:25,356 DEBUG Device SN#: SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 19:10:25,357 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:25,357 DEBUG Executing Command. RESET_SYSTEM() [Thread-1] 2012-03-18 19:10:25,359 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=32) [MainThread] 2012-03-18 19:10:25,362 DEBUG Executing Command. SET_NETWORK_KEY(network_number=0, network_key='\xa8\xa4#\xb9\xf5^c\xc1') [Thread-1] 2012-03-18 19:10:25,364 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=70, msg_code=0) [MainThread] 2012-03-18 19:10:25,367 DEBUG Executing Command. ASSIGN_CHANNEL(channel_number=0, channel_type=0, network_number=0) [Thread-1] 2012-03-18 19:10:25,369 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=66, msg_code=0) [MainThread] 2012-03-18 19:10:25,372 DEBUG Executing Command. SET_CHANNEL_ID(channel_number=0, device_number=0, device_type_id=0, trans_type=0) [Thread-1] 2012-03-18 19:10:25,374 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=81, msg_code=0) [MainThread] 2012-03-18 19:10:25,377 DEBUG Executing Command. SET_CHANNEL_PERIOD(channel_number=0, messaging_period=4096) [Thread-1] 2012-03-18 19:10:25,379 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=67, msg_code=0) [MainThread] 2012-03-18 19:10:25,381 DEBUG Executing Command. SET_CHANNEL_SEARCH_TIMEOUT(channel_number=0, search_timeout=255) [Thread-1] 2012-03-18 19:10:25,383 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=68, msg_code=0) [MainThread] 2012-03-18 19:10:25,387 DEBUG Executing Command. SET_CHANNEL_RF_FREQ(channel_number=0, rf_freq=50) [Thread-1] 2012-03-18 19:10:25,389 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=69, msg_code=0) [MainThread] 2012-03-18 19:10:25,393 DEBUG Executing Command. SET_SEARCH_WAVEFORM(channel_number=0, waveform=83) [Thread-1] 2012-03-18 19:10:25,395 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=73, msg_code=0) [MainThread] 2012-03-18 19:10:25,399 DEBUG Executing Command. OPEN_CHANNEL(channel_number=0) [Thread-1] 2012-03-18 19:10:25,401 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=75, msg_code=0) [MainThread] 2012-03-18 19:10:25,407 DEBUG Executing Command. ReadData(channel_number=0) [Thread-1] 2012-03-18 19:10:25,409 DEBUG Processing reply. CHANNEL_STATUS(channel_number=0, channel_status=2) [Thread-1] 2012-03-18 19:10:25,914 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 777, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 505, in recv except IOError as (err, msg): ValueError: need more than 1 value to unpack [MainThread] 2012-03-18 19:10:26,409 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 277, in search beacon = Beacon.unpack(self.channel.recv_broadcast(timeout=timeout - time.time())) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 862, in recv_broadcast return self._session._send(ReadData(self.channel_number, RecvBroadcastData), timeout=timeout).data File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,410 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:26,411 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,411 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,411 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:26,412 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,412 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,413 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:26,413 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,413 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,417 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,417 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 554, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed.

braiden commented 12 years ago

Can you run again with '--verbose' command line arg and reply with output? Also does it fail every time? On Mar 18, 2012 2:22 PM, "FaultyG" < reply@reply.github.com> wrote:

Trying to connect to my 405cx with Ubuntu 12.04. Installed the packages as described in the installation procedure, packets gotten from Ubuntu-repo. python-ant-downloader from branch.

I had worse luck before connecting my 405cx to windows and upgrading the software (as well as setting the correct time) (batteries been dead for ages).

This the log I get now (ran with 'sudo ant-downloader' as there seems to be a permission issue with the udev-way):

[MainThread] 2012-03-18 19:10:25,335 DEBUG Executing Command. RESET_SYSTEM() [Thread-1] 2012-03-18 19:10:25,340 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=0) [MainThread] 2012-03-18 19:10:25,342 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=84) [Thread-1] 2012-03-18 19:10:25,344 DEBUG Processing reply. CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 19:10:25,346 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=62) [Thread-1] 2012-03-18 19:10:25,349 DEBUG Processing reply. VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 19:10:25,352 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=97) [Thread-1] 2012-03-18 19:10:25,354 DEBUG Processing reply. SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 19:10:25,356 DEBUG Device Capabilities: CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 19:10:25,356 DEBUG Device ANT Version: VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 19:10:25,356 DEBUG Device SN#: SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 19:10:25,357 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:25,357 DEBUG Executing Command. RESET_SYSTEM() [Thread-1] 2012-03-18 19:10:25,359 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=32) [MainThread] 2012-03-18 19:10:25,362 DEBUG Executing Command. SET_NETWORK_KEY(network_number=0, network_key='\xa8\xa4#\xb9\xf5^c\xc1') [Thread-1] 2012-03-18 19:10:25,364 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=70, msg_code=0) [MainThread] 2012-03-18 19:10:25,367 DEBUG Executing Command. ASSIGN_CHANNEL(channel_number=0, channel_type=0, network_number=0) [Thread-1] 2012-03-18 19:10:25,369 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=66, msg_code=0) [MainThread] 2012-03-18 19:10:25,372 DEBUG Executing Command. SET_CHANNEL_ID(channel_number=0, device_number=0, device_type_id=0, trans_type=0) [Thread-1] 2012-03-18 19:10:25,374 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=81, msg_code=0) [MainThread] 2012-03-18 19:10:25,377 DEBUG Executing Command. SET_CHANNEL_PERIOD(channel_number=0, messaging_period=4096) [Thread-1] 2012-03-18 19:10:25,379 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=67, msg_code=0) [MainThread] 2012-03-18 19:10:25,381 DEBUG Executing Command. SET_CHANNEL_SEARCH_TIMEOUT(channel_number=0, search_timeout=255) [Thread-1] 2012-03-18 19:10:25,383 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=68, msg_code=0) [MainThread] 2012-03-18 19:10:25,387 DEBUG Executing Command. SET_CHANNEL_RF_FREQ(channel_number=0, rf_freq=50) [Thread-1] 2012-03-18 19:10:25,389 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=69, msg_code=0) [MainThread] 2012-03-18 19:10:25,393 DEBUG Executing Command. SET_SEARCH_WAVEFORM(channel_number=0, waveform=83) [Thread-1] 2012-03-18 19:10:25,395 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=73, msg_code=0) [MainThread] 2012-03-18 19:10:25,399 DEBUG Executing Command. OPEN_CHANNEL(channel_number=0) [Thread-1] 2012-03-18 19:10:25,401 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=75, msg_code=0) [MainThread] 2012-03-18 19:10:25,407 DEBUG Executing Command. ReadData(channel_number=0) [Thread-1] 2012-03-18 19:10:25,409 DEBUG Processing reply. CHANNEL_STATUS(channel_number=0, channel_status=2) [Thread-1] 2012-03-18 19:10:25,914 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 777, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 505, in recv except IOError as (err, msg): ValueError: need more than 1 value to unpack [MainThread] 2012-03-18 19:10:26,409 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 277, in search beacon = Beacon.unpack(self.channel.recv_broadcast(timeout=timeout - time.time())) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 862, in recv_broadcast return self._session._send(ReadData(self.channel_number, RecvBroadcastData), timeout=timeout).data File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,410 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:26,411 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,411 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,411 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:26,412 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,412 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,413 INFO Searching for ANT devices. [MainThread] 2012-03-18 19:10:26,413 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,413 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 19:10:26,417 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 19:10:26,417 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 554, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed.


Reply to this email directly or view it on GitHub: https://github.com/braiden/python-ant-downloader/issues/11

JanneKro commented 12 years ago

Yes, fails everytime. I just tried with my desktop (also Ubuntu 12.04) and it seemed to work just fine. So it is most likely a configuration error on this particular machine. This is not important for me as I'm not intending to use the 405cx on this machine. If it is important for you to know what this problem might be for future reference, just let me know what you want me to do in order to debug this.

Here's the verbose log: [EDIT: Sorry, I don't think the watch was necessarily active when I posted the log. New log coming shortly.

JanneKro commented 12 years ago

Here's the verbose log:

sudo ant-downloader --verbose Setting configuration on <usb.core.Device object at 0x2db6110> Claiming interface [MainThread] 2012-03-18 20:12:35,862 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:12:35,863 DEBUG SEND: a4014a00ef [Thread-1] 2012-03-18 20:12:35,866 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,867 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,878 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,879 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,879 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,880 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,880 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,881 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,881 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,881 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,882 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,882 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,883 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,883 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,883 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,884 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,884 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,884 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,885 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,885 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,885 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,886 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,886 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,886 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,887 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,887 DEBUG RECV: a40340000106e0 [Thread-1] 2012-03-18 20:12:35,887 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=6) [Thread-1] 2012-03-18 20:12:35,888 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,888 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,888 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,888 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,889 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,889 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,889 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,889 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,889 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,889 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,890 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,890 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,890 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,890 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,890 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,890 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,891 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,891 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,891 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,891 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,891 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,891 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,892 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,892 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,892 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,892 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,892 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,892 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,893 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,893 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,893 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,893 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,893 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,893 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,893 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,894 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,894 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,894 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,894 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,894 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,894 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,895 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,895 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,895 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,895 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,895 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,895 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,896 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,896 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,896 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,896 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,896 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,896 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,897 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,897 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,897 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,897 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,897 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,897 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,897 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,898 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,898 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,898 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,898 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,898 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,898 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,898 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,899 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,899 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,899 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,899 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,899 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,899 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,899 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,900 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,900 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,900 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,900 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,900 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,900 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,901 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,901 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,901 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,901 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,901 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,901 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,901 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,902 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,902 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,902 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,902 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,902 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,902 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,902 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,903 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,903 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,903 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,903 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,903 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,903 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,903 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,903 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,904 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,904 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,904 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,904 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,904 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,904 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,905 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,905 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,905 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,905 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,905 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,905 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,905 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,906 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,906 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,906 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,906 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,906 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,906 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,906 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,907 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,907 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,907 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,907 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,907 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,907 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,907 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,908 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,908 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,908 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,908 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,908 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,908 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,908 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,909 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,909 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,909 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,909 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,909 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,909 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,909 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,910 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,910 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,910 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,910 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,910 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,910 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,910 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,911 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,911 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,911 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,911 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,911 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,911 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,912 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,932 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,933 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,933 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,933 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,933 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,933 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,933 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,934 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,934 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,934 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,934 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,934 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,934 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,935 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,935 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,935 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,935 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,935 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,935 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,935 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,936 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,936 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,936 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,936 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,936 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,937 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,937 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,937 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,937 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,937 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,938 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,938 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,938 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,938 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,939 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,939 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,939 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,939 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,939 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,940 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,940 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,940 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,940 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,940 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,941 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,941 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,941 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,941 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,942 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,942 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,942 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,942 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,942 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,943 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,943 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,943 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,943 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,943 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,944 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,944 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,944 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,944 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,944 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,945 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,945 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,945 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,945 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,945 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,946 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,946 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,946 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,946 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,946 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,947 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,947 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,947 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,947 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,948 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,948 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,948 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,948 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,948 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,949 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,949 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,949 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,949 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,949 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,949 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,949 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,949 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,950 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,950 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,950 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,950 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,950 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,950 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,951 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:12:35,951 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:12:35,951 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,951 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,951 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,951 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,951 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,952 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,952 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,952 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,952 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,952 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,952 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,952 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,956 DEBUG RECV: a40340000134d2 [Thread-1] 2012-03-18 20:12:35,957 ERROR USB Serial buffer overflow. PC reading too slow. [Thread-1] 2012-03-18 20:12:35,957 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=52) [Thread-1] 2012-03-18 20:12:35,957 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:12:35,957 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:12:35,957 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:12:35,960 DEBUG RECV: a4016f20ea [Thread-1] 2012-03-18 20:12:35,960 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=32) [MainThread] 2012-03-18 20:12:35,984 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=84) [MainThread] 2012-03-18 20:12:35,984 DEBUG SEND: a4024d0054bf [Thread-1] 2012-03-18 20:12:35,985 DEBUG RECV: a40654080300ba360071 [Thread-1] 2012-03-18 20:12:35,991 DEBUG Processing reply. CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 20:12:35,996 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=62) [MainThread] 2012-03-18 20:12:35,998 DEBUG SEND: a4024d003ed5 [Thread-1] 2012-03-18 20:12:35,999 DEBUG RECV: a40b3e414a4b312e3034524146009f [Thread-1] 2012-03-18 20:12:36,000 DEBUG Processing reply. VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 20:12:36,000 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=97) [MainThread] 2012-03-18 20:12:36,000 DEBUG SEND: a4024d00618a [Thread-1] 2012-03-18 20:12:36,003 DEBUG RECV: a40461d3ee1600ea [Thread-1] 2012-03-18 20:12:36,004 DEBUG Processing reply. SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 20:12:36,005 DEBUG Device Capabilities: CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 20:12:36,006 DEBUG Device ANT Version: VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 20:12:36,006 DEBUG Device SN#: SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 20:12:36,006 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:12:36,006 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:12:36,007 DEBUG SEND: a4014a00ef [Thread-1] 2012-03-18 20:12:36,008 DEBUG RECV: a4016f20ea [Thread-1] 2012-03-18 20:12:36,009 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=32) [MainThread] 2012-03-18 20:12:36,013 DEBUG Executing Command. SET_NETWORK_KEY(network_number=0, network_key='\xa8\xa4#\xb9\xf5^c\xc1') [MainThread] 2012-03-18 20:12:36,013 DEBUG SEND: a4094600a8a423b9f55e63c174 [Thread-1] 2012-03-18 20:12:36,015 DEBUG RECV: a40340004600a1 [Thread-1] 2012-03-18 20:12:36,020 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=70, msg_code=0) [MainThread] 2012-03-18 20:12:36,025 DEBUG Executing Command. ASSIGN_CHANNEL(channel_number=0, channel_type=0, network_number=0) [MainThread] 2012-03-18 20:12:36,025 DEBUG SEND: a40342000000e5 [Thread-1] 2012-03-18 20:12:36,027 DEBUG RECV: a40340004200a5 [Thread-1] 2012-03-18 20:12:36,028 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=66, msg_code=0) [MainThread] 2012-03-18 20:12:36,033 DEBUG Executing Command. SET_CHANNEL_ID(channel_number=0, device_number=0, device_type_id=0, trans_type=0) [MainThread] 2012-03-18 20:12:36,035 DEBUG SEND: a405510000000000f0 [Thread-1] 2012-03-18 20:12:36,037 DEBUG RECV: a40340005100b6 [Thread-1] 2012-03-18 20:12:36,038 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=81, msg_code=0) [MainThread] 2012-03-18 20:12:36,043 DEBUG Executing Command. SET_CHANNEL_PERIOD(channel_number=0, messaging_period=4096) [MainThread] 2012-03-18 20:12:36,044 DEBUG SEND: a40343000010f4 [Thread-1] 2012-03-18 20:12:36,045 DEBUG RECV: a40340004300a4 [Thread-1] 2012-03-18 20:12:36,046 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=67, msg_code=0) [MainThread] 2012-03-18 20:12:36,050 DEBUG Executing Command. SET_CHANNEL_SEARCH_TIMEOUT(channel_number=0, search_timeout=255) [MainThread] 2012-03-18 20:12:36,052 DEBUG SEND: a4024400ff1d [Thread-1] 2012-03-18 20:12:36,055 DEBUG RECV: a40340004400a3 [Thread-1] 2012-03-18 20:12:36,056 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=68, msg_code=0) [MainThread] 2012-03-18 20:12:36,060 DEBUG Executing Command. SET_CHANNEL_RF_FREQ(channel_number=0, rf_freq=50) [MainThread] 2012-03-18 20:12:36,061 DEBUG SEND: a402450032d1 [Thread-1] 2012-03-18 20:12:36,062 DEBUG RECV: a40340004500a2 [Thread-1] 2012-03-18 20:12:36,063 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=69, msg_code=0) [MainThread] 2012-03-18 20:12:36,067 DEBUG Executing Command. SET_SEARCH_WAVEFORM(channel_number=0, waveform=83) [MainThread] 2012-03-18 20:12:36,068 DEBUG SEND: a40349005300bd [Thread-1] 2012-03-18 20:12:36,069 DEBUG RECV: a40340004900ae [Thread-1] 2012-03-18 20:12:36,070 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=73, msg_code=0) [MainThread] 2012-03-18 20:12:36,074 DEBUG Executing Command. OPEN_CHANNEL(channel_number=0) [MainThread] 2012-03-18 20:12:36,075 DEBUG SEND: a4014b00ee [Thread-1] 2012-03-18 20:12:36,076 DEBUG RECV: a40340004b00ac [Thread-1] 2012-03-18 20:12:36,077 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=75, msg_code=0) [MainThread] 2012-03-18 20:12:36,081 DEBUG Executing Command. ReadData(channel_number=0) [MainThread] 2012-03-18 20:12:36,081 DEBUG SEND: a4024d0052b9 [Thread-1] 2012-03-18 20:12:36,083 DEBUG RECV: a402520002f6 [Thread-1] 2012-03-18 20:12:36,084 DEBUG Processing reply. CHANNEL_STATUS(channel_number=0, channel_status=2) [Thread-1] 2012-03-18 20:12:36,589 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 777, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 505, in recv except IOError as (err, msg): ValueError: need more than 1 value to unpack [MainThread] 2012-03-18 20:12:37,084 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 277, in search beacon = Beacon.unpack(self.channel.recv_broadcast(timeout=timeout - time.time())) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 862, in recv_broadcast return self._session._send(ReadData(self.channel_number, RecvBroadcastData), timeout=timeout).data File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:12:37,085 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:12:37,085 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:12:37,086 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:12:37,086 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:12:37,086 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:12:37,087 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:12:37,087 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:12:37,087 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:12:37,088 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:12:37,088 DEBUG SEND: a4094f004403000000000000a5 [MainThread] 2012-03-18 20:12:37,091 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:12:37,092 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 554, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed.

JanneKro commented 12 years ago

hmm, I did manage to get it say something along the lines of "Connected by no data to retrieve", but that too was ended by an exception and " ValueError: need more than 1 value to unpack". Unfortunately I don't have the log for that as the buffer was set too low. I recorded a new training and that's when I got the above.

JanneKro commented 12 years ago

And now I managed to get "Device has data".. but it still fails.

sudo ant-downloader --verbose [sudo] password for cam: Setting configuration on <usb.core.Device object at 0x17c4110> Claiming interface [MainThread] 2012-03-18 20:51:03,073 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:03,075 DEBUG SEND: a4014a00ef [Thread-1] 2012-03-18 20:51:03,085 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,085 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,086 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,086 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,087 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,087 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,087 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,088 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,088 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,088 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,089 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,089 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,090 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,090 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,090 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,091 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,091 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,092 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,092 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,092 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,093 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,093 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,093 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,094 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,094 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,094 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,095 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,095 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,095 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,096 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,096 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,096 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,097 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,097 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,097 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,098 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,098 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,098 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,099 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,099 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,099 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,100 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,100 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,100 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,101 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,101 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,101 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,102 DEBUG RECV: a40340000106e0 [Thread-1] 2012-03-18 20:51:03,102 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=6) [Thread-1] 2012-03-18 20:51:03,102 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,103 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,103 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,103 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,104 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,104 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,104 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,104 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,105 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,105 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,105 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,105 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,105 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,105 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,106 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,106 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,106 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,106 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,106 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,106 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,107 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,107 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,107 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,107 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,107 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,107 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,108 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,108 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,108 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,108 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,108 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,108 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,109 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,109 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,109 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,109 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,109 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,109 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,109 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,110 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,110 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,110 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,110 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,110 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,110 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,111 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,111 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,111 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,111 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,111 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,111 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,112 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,112 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,112 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,112 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,112 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,112 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,112 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,113 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,113 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,113 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,113 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,113 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,113 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,113 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,114 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,114 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,114 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,114 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,114 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,114 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,115 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,115 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,115 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,115 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,115 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,115 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,115 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,116 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,116 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,116 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,116 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,116 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,116 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,116 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,117 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,117 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,117 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,117 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,117 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,117 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,117 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,118 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,118 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,118 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,118 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,118 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,118 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,118 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,119 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,119 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,119 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,119 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,119 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,119 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,119 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,120 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,120 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,120 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,120 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,120 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,120 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,120 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,121 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,121 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,121 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,121 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,121 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,121 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,122 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,122 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,122 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,122 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,122 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,122 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,122 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,123 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,123 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,123 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,123 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,123 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,123 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,123 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,124 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,124 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,124 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,124 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,124 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,124 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,125 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,125 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,125 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,125 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,125 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,125 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,125 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,125 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,126 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,145 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,146 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,146 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,146 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,146 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,146 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,146 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,146 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,147 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,147 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,147 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,147 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,147 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,147 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,147 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,148 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,148 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,148 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,148 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,148 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,148 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,149 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,149 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,149 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,149 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,150 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,150 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,150 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,150 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,151 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,151 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,151 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,151 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,152 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,152 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,152 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,152 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,153 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,153 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,153 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,153 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,153 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,153 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,153 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,154 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,154 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,154 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,154 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,154 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,154 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,154 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,155 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,155 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,155 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,155 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,155 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,155 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,155 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,156 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,156 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,156 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,156 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,156 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,156 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,157 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,157 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,157 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,157 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,157 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,157 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,158 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,158 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,158 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,158 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,158 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,158 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,158 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,159 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,159 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,159 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,159 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,159 DEBUG RECV: a4094e00432100030100020081 [Thread-1] 2012-03-18 20:51:03,159 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C!\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,159 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,160 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,160 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,160 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,160 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,160 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,160 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,160 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,161 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,161 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,161 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,161 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,165 DEBUG RECV: a40340000134d2 [Thread-1] 2012-03-18 20:51:03,165 ERROR USB Serial buffer overflow. PC reading too slow. [Thread-1] 2012-03-18 20:51:03,165 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=52) [Thread-1] 2012-03-18 20:51:03,165 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,165 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,166 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,168 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,168 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,168 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,168 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,168 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,169 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,169 DEBUG RECV: a4094e00432900030100020089 [Thread-1] 2012-03-18 20:51:03,169 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C)\x00\x03\x01\x00\x02\x00') [Thread-1] 2012-03-18 20:51:03,169 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,169 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,169 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,169 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,170 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,170 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,170 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,170 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,170 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,170 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,171 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,171 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,171 DEBUG RECV: a40340000135d3 [Thread-1] 2012-03-18 20:51:03,171 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=53) [Thread-1] 2012-03-18 20:51:03,171 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,171 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,172 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,215 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,216 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,216 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,340 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,340 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,340 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,465 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,465 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,466 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [Thread-1] 2012-03-18 20:51:03,590 DEBUG RECV: a40340000102e4 [Thread-1] 2012-03-18 20:51:03,590 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-18 20:51:03,591 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=1, msg_code=2) [MainThread] 2012-03-18 20:51:03,595 WARNING Retryable error. 5 try(s) remaining. No reply to command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:03,595 DEBUG SEND: a4014a00ef [Thread-1] 2012-03-18 20:51:03,597 DEBUG RECV: a4016f20ea [Thread-1] 2012-03-18 20:51:03,597 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=32) [MainThread] 2012-03-18 20:51:03,601 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=84) [MainThread] 2012-03-18 20:51:03,602 DEBUG SEND: a4024d0054bf [Thread-1] 2012-03-18 20:51:03,604 DEBUG RECV: a40654080300ba360071 [Thread-1] 2012-03-18 20:51:03,604 DEBUG Processing reply. CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 20:51:03,608 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=62) [MainThread] 2012-03-18 20:51:03,609 DEBUG SEND: a4024d003ed5 [Thread-1] 2012-03-18 20:51:03,611 DEBUG RECV: a40b3e414a4b312e3034524146009f [Thread-1] 2012-03-18 20:51:03,611 DEBUG Processing reply. VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 20:51:03,616 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=97) [MainThread] 2012-03-18 20:51:03,617 DEBUG SEND: a4024d00618a [Thread-1] 2012-03-18 20:51:03,619 DEBUG RECV: a40461d3ee1600ea [Thread-1] 2012-03-18 20:51:03,619 DEBUG Processing reply. SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 20:51:03,623 DEBUG Device Capabilities: CAPABILITIES(max_channels=8, max_networks=3, standard_opts=0, advanced_opts1=186, advanced_opts2=54) [MainThread] 2012-03-18 20:51:03,624 DEBUG Device ANT Version: VERSION(ant_version='AJK1.04RAF\x00') [MainThread] 2012-03-18 20:51:03,624 DEBUG Device SN#: SERIAL_NUMBER(serial_number=1502931) [MainThread] 2012-03-18 20:51:03,625 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:51:03,625 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:03,626 DEBUG SEND: a4014a00ef [Thread-1] 2012-03-18 20:51:03,628 DEBUG RECV: a4016f20ea [Thread-1] 2012-03-18 20:51:03,628 DEBUG Processing reply. STARTUP_MESSAGE(startup_message=32) [MainThread] 2012-03-18 20:51:03,633 DEBUG Executing Command. SET_NETWORK_KEY(network_number=0, network_key='\xa8\xa4#\xb9\xf5^c\xc1') [MainThread] 2012-03-18 20:51:03,634 DEBUG SEND: a4094600a8a423b9f55e63c174 [Thread-1] 2012-03-18 20:51:03,636 DEBUG RECV: a40340004600a1 [Thread-1] 2012-03-18 20:51:03,636 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=70, msg_code=0) [MainThread] 2012-03-18 20:51:03,638 DEBUG Executing Command. ASSIGN_CHANNEL(channel_number=0, channel_type=0, network_number=0) [MainThread] 2012-03-18 20:51:03,639 DEBUG SEND: a40342000000e5 [Thread-1] 2012-03-18 20:51:03,640 DEBUG RECV: a40340004200a5 [Thread-1] 2012-03-18 20:51:03,640 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=66, msg_code=0) [MainThread] 2012-03-18 20:51:03,642 DEBUG Executing Command. SET_CHANNEL_ID(channel_number=0, device_number=0, device_type_id=0, trans_type=0) [MainThread] 2012-03-18 20:51:03,642 DEBUG SEND: a405510000000000f0 [Thread-1] 2012-03-18 20:51:03,644 DEBUG RECV: a40340005100b6 [Thread-1] 2012-03-18 20:51:03,644 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=81, msg_code=0) [MainThread] 2012-03-18 20:51:03,646 DEBUG Executing Command. SET_CHANNEL_PERIOD(channel_number=0, messaging_period=4096) [MainThread] 2012-03-18 20:51:03,646 DEBUG SEND: a40343000010f4 [Thread-1] 2012-03-18 20:51:03,648 DEBUG RECV: a40340004300a4 [Thread-1] 2012-03-18 20:51:03,648 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=67, msg_code=0) [MainThread] 2012-03-18 20:51:03,650 DEBUG Executing Command. SET_CHANNEL_SEARCH_TIMEOUT(channel_number=0, search_timeout=255) [MainThread] 2012-03-18 20:51:03,650 DEBUG SEND: a4024400ff1d [Thread-1] 2012-03-18 20:51:03,652 DEBUG RECV: a40340004400a3 [Thread-1] 2012-03-18 20:51:03,652 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=68, msg_code=0) [MainThread] 2012-03-18 20:51:03,654 DEBUG Executing Command. SET_CHANNEL_RF_FREQ(channel_number=0, rf_freq=50) [MainThread] 2012-03-18 20:51:03,654 DEBUG SEND: a402450032d1 [Thread-1] 2012-03-18 20:51:03,656 DEBUG RECV: a40340004500a2 [Thread-1] 2012-03-18 20:51:03,656 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=69, msg_code=0) [MainThread] 2012-03-18 20:51:03,660 DEBUG Executing Command. SET_SEARCH_WAVEFORM(channel_number=0, waveform=83) [MainThread] 2012-03-18 20:51:03,661 DEBUG SEND: a40349005300bd [Thread-1] 2012-03-18 20:51:03,662 DEBUG RECV: a40340004900ae [Thread-1] 2012-03-18 20:51:03,663 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=73, msg_code=0) [MainThread] 2012-03-18 20:51:03,667 DEBUG Executing Command. OPEN_CHANNEL(channel_number=0) [MainThread] 2012-03-18 20:51:03,667 DEBUG SEND: a4014b00ee [Thread-1] 2012-03-18 20:51:03,669 DEBUG RECV: a40340004b00ac [Thread-1] 2012-03-18 20:51:03,669 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=75, msg_code=0) [MainThread] 2012-03-18 20:51:03,674 DEBUG Executing Command. ReadData(channel_number=0) [MainThread] 2012-03-18 20:51:03,674 DEBUG SEND: a4024d0052b9 [Thread-1] 2012-03-18 20:51:03,680 DEBUG RECV: a402520002f6 [Thread-1] 2012-03-18 20:51:03,681 DEBUG Processing reply. CHANNEL_STATUS(channel_number=0, channel_status=2) [Thread-1] 2012-03-18 20:51:03,717 DEBUG RECV: a4094e00432900030100020089 [Thread-1] 2012-03-18 20:51:03,718 DEBUG Processing reply. RECV_BROADCAST_DATA(channel_number=0, data='C)\x00\x03\x01\x00\x02\x00') [MainThread] 2012-03-18 20:51:03,746 DEBUG Executing Command. REQUEST_MESSAGE(channel_number=0, msg_id=81) [MainThread] 2012-03-18 20:51:03,746 DEBUG SEND: a4024d0051ba [Thread-1] 2012-03-18 20:51:03,748 DEBUG RECV: a40551007eba010530 [Thread-1] 2012-03-18 20:51:03,748 DEBUG Processing reply. CHANNEL_ID(channel_number=0, device_number=47742, device_type_id=1, man_id=5) [MainThread] 2012-03-18 20:51:03,748 DEBUG Got ANT-FS Beacon. device_number=0xba7e Beacon{'auth_type': 3, 'pairing_enabled': 0, 'device_state': 0, 'period': 1, 'data_availible': 32, 'descriptor': 131073, 'upload_enabled': 0, 'data_page_id': 67, 'data': '', 'status_2': 0, 'status_1': 41} [MainThread] 2012-03-18 20:51:03,748 INFO Device has data. Linking. [MainThread] 2012-03-18 20:51:03,749 DEBUG Setting period to match device, hz=1 [MainThread] 2012-03-18 20:51:03,749 DEBUG Executing Command. SET_CHANNEL_PERIOD(channel_number=0, messaging_period=32768) [MainThread] 2012-03-18 20:51:03,749 DEBUG SEND: a4034300008064 [Thread-1] 2012-03-18 20:51:03,752 DEBUG RECV: a40340004300a4 [Thread-1] 2012-03-18 20:51:03,752 DEBUG Processing reply. CHANNEL_EVENT(channel_number=0, msg_id=67, msg_code=0) [MainThread] 2012-03-18 20:51:03,755 DEBUG Linking with device. freq=2403mhz [MainThread] 2012-03-18 20:51:03,755 DEBUG Executing Command. SEND_ACKNOWLEDGED_DATA(channel_number=0, data='D\x02\x03\x04A\x0c\x00\x00') [MainThread] 2012-03-18 20:51:03,755 DEBUG SEND: a4094f0044020304410c0000ee [Thread-1] 2012-03-18 20:51:04,256 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 777, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 505, in recv except IOError as (err, msg): ValueError: need more than 1 value to unpack [MainThread] 2012-03-18 20:51:04,757 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 86, in downloader host.link() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 333, in link self.channel.send_acknowledged(link.pack()) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 847, in send_acknowledged self._session._send(cmd, timeout=timeout, retry=retry) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:51:04,758 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:51:04,759 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:04,759 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:51:04,760 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:51:04,760 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:04,760 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:51:04,761 INFO Searching for ANT devices. [MainThread] 2012-03-18 20:51:04,761 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:04,761 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-18 20:51:04,762 DEBUG SEND: a4094f004403000000000000a5 [MainThread] 2012-03-18 20:51:04,765 DEBUG Executing Command. RESET_SYSTEM() [MainThread] 2012-03-18 20:51:04,765 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 554, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 566, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 648, in _send raise AntError("Session closed.") AntError: Session closed.

braiden commented 12 years ago

An IOError is being raised, but it contains only one value, not two. My code is stupidly masking the real cause of the failure.

If you want to continue to debug this issue further, get the "ioerror" branch from github: git clone git://github.com/braiden/python-ant-downloader.git cd python-ant-downloader git checkout ioerror

then run ./python-ant-downloader.py

And report back with whatever exception you get.

FYI, someone else just mentions that in newer versions of udev the correct rule is : SUBSYSTEM=="usb", ATTR{idVendor}=="0fcf", ATTR{idProduct}=="1008", MODE="666" So you can try that to if you don't want to run as root

Regards,

JanneKro commented 12 years ago

Thanks for the udev advice! I will try that as well as the other branch during the weekend, if time allows.

JanneKro commented 12 years ago

Here I'm running the ioerror-branch a number of times:

am@janne-ThinkPad-X61s:~/Development$ ant-downloader Setting configuration on <usb.core.Device object at 0x1168310> Claiming interface [Thread-1] 2012-03-22 18:33:26,993 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,993 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,994 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,994 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,994 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,994 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,995 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,995 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,995 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,996 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,996 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,996 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,996 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,996 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,997 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,997 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,997 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,997 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,998 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,998 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,998 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,999 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,999 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,999 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:26,999 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,000 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,000 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,000 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,000 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,001 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,001 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,001 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,001 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,002 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,002 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,002 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,002 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,003 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,003 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,003 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,003 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,003 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,004 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,004 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,004 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,004 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,005 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,005 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,005 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,005 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,006 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,006 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,006 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,006 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,006 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,007 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,007 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,008 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,008 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,008 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,008 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,008 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,009 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,009 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,009 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,009 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,010 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,010 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,010 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,010 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,010 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,011 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,011 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,011 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,011 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,012 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,012 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,012 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,012 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,013 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,013 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,013 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,013 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,013 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,014 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,014 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,014 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,014 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,014 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,015 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:27,018 ERROR USB Serial buffer overflow. PC reading too slow. [Thread-1] 2012-03-22 18:33:27,018 WARNING Failed to receive RF beacon at expected period. channel_number=0 [MainThread] 2012-03-22 18:33:27,056 INFO Searching for ANT devices. [Thread-1] 2012-03-22 18:33:27,611 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 785, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 512, in recv try: msg = e.args[1] IndexError: tuple index out of range [MainThread] 2012-03-22 18:33:28,104 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 277, in search beacon = Beacon.unpack(self.channel.recv_broadcast(timeout=timeout - time.time())) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 870, in recv_broadcast return self._session._send(ReadData(self.channel_number, RecvBroadcastData), timeout=timeout).data File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:28,106 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:28,106 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:28,107 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:28,107 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:28,108 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:28,108 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:28,111 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 562, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. cam@janne-ThinkPad-X61s:~/Development$ cam@janne-ThinkPad-X61s:~/Development$ cam@janne-ThinkPad-X61s:~/Development$ ant-downloader Setting configuration on <usb.core.Device object at 0x1647310> Claiming interface [Thread-1] 2012-03-22 18:33:32,617 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,620 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,620 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,621 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,621 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,621 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,622 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,622 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,623 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,623 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,623 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,624 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,624 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,624 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,629 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,754 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:32,879 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:33,004 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:33,129 WARNING Failed to receive RF beacon at expected period. channel_number=0 [MainThread] 2012-03-22 18:33:33,134 WARNING Retryable error. 5 try(s) remaining. No reply to command. RESET_SYSTEM() [MainThread] 2012-03-22 18:33:33,154 INFO Searching for ANT devices. [Thread-1] 2012-03-22 18:33:33,698 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 785, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 512, in recv try: msg = e.args[1] IndexError: tuple index out of range [MainThread] 2012-03-22 18:33:34,194 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 277, in search beacon = Beacon.unpack(self.channel.recv_broadcast(timeout=timeout - time.time())) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 870, in recv_broadcast return self._session._send(ReadData(self.channel_number, RecvBroadcastData), timeout=timeout).data File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:34,196 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:34,196 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:34,196 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:34,197 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:34,197 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:34,198 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:34,201 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 562, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. cam@janne-ThinkPad-X61s:~/Development$ ant-downloader Setting configuration on <usb.core.Device object at 0x2c90310> Claiming interface [Thread-1] 2012-03-22 18:33:46,783 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,783 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,784 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,784 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,784 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,785 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,785 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,786 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,786 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,786 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,787 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,787 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,787 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,788 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,788 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,789 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,789 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,790 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,790 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,791 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,791 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,792 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,792 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,793 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,793 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,794 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,794 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,795 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,795 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,796 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,796 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,797 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,797 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,797 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,798 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,798 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,799 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,799 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,800 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,800 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,800 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,800 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,801 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,801 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,801 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,801 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,802 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,802 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,802 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,802 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,803 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,803 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,803 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,803 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,803 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,804 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,804 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,804 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,804 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,805 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,805 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,805 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,805 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,805 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,806 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,806 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,806 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,807 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,807 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,807 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,807 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,808 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,808 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,808 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,808 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,808 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,809 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,809 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,809 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,809 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,809 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,810 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,810 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,810 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,810 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,810 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,811 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2012-03-22 18:33:46,811 WARNING Failed to receive RF beacon at expected period. channel_number=0 [MainThread] 2012-03-22 18:33:46,852 INFO Searching for ANT devices. [Thread-1] 2012-03-22 18:33:47,388 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 785, in loop for cmd in self.core.recv(): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 512, in recv try: msg = e.args[1] IndexError: tuple index out of range [MainThread] 2012-03-22 18:33:47,885 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 277, in search beacon = Beacon.unpack(self.channel.recv_broadcast(timeout=timeout - time.time())) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 870, in recv_broadcast return self._session._send(ReadData(self.channel_number, RecvBroadcastData), timeout=timeout).data File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:47,887 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:47,887 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:47,888 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:47,888 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:47,888 INFO Searching for ANT devices. [MainThread] 2012-03-22 18:33:47,889 WARNING Caught error while communicating with device, will retry. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 83, in downloader include_devices_with_no_data=args.force or not args.daemon) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 275, in search self._open_antfs_search_channel() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 411, in _open_antfs_search_channel self.ant_session.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed. [MainThread] 2012-03-22 18:33:47,892 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/main.py", line 115, in downloader try: host.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/antfs.py", line 241, in close self.ant_session.close() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 562, in close self.reset_system() File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 574, in reset_system self._send(ResetSystem(), timeout=.5, retry=5) File "/usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg/antd/ant.py", line 656, in _send raise AntError("Session closed.") AntError: Session closed.

jzacsh commented 11 years ago

Hey, I'd love to help debug further (looks like maybe I have a totally different issue)

(running Ubuntu 12.04, just plugged the ANT+ USB stick in, nothing else is using it)...

$ git checkout ioerror
$ ./ant-downloader.py

I get the following:

/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py:36: UserWarning: Module antd was already imported from /home/jzacsh/tmp/gad/python-ant-downloader/antd/__init__.pyc, but /usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg is being added to sys.path
  import pkg_resources
[MainThread]    2013-01-06 17:47:16,390 INFO    Found device with vid(0x0fcf) pid(0x1008), but interface already claimed.
Traceback (most recent call last):
  File "./ant-downloader.py", line 3, in <module>
    downloader()
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/main.py", line 73, in downloader
    host = antd.cfg.create_antfs_host()
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 112, in create_antfs_host
    host = antfs.Host(create_ant_session(), keys)
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 99, in create_ant_session
    session = ant.Session(create_ant_core())
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 95, in create_ant_core
    return ant.Core(create_hardware())
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 91, in create_hardware
    return hw.UsbHardware(id_vendor, id_product, bulk_endpoint)
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/hw.py", line 59, in __init__
    raise IOError(errno.ENOENT, "No available device matching vid(0x%04x) pid(0x%04x)." % (id_vendor, id_product))
IOError: [Errno 2] No available device matching vid(0x0fcf) pid(0x1008).

Update Ah, I see (I was just looking at the line starting with [MainThread]). Though, I'm not sure how to uninstall my global python-ant-downloader from previous attempts (not very familiar with pypi). Update Okay, uninstalled old stuff following stackoverflow tip, now get the same thing about the USB stick being claimed already:

[MainThread]    2013-01-06 18:01:38,498 INFO    Found device with vid(0x0fcf) pid(0x1008), but interface already claimed.
Traceback (most recent call last):
  File "./ant-downloader.py", line 3, in <module>
    downloader()
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/main.py", line 73, in downloader
    host = antd.cfg.create_antfs_host()
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 112, in create_antfs_host
    host = antfs.Host(create_ant_session(), keys)
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 99, in create_ant_session
    session = ant.Session(create_ant_core())
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 95, in create_ant_core
    return ant.Core(create_hardware())
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 91, in create_hardware
    return hw.UsbHardware(id_vendor, id_product, bulk_endpoint)
  File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/hw.py", line 59, in __init__
    raise IOError(errno.ENOENT, "No available device matching vid(0x%04x) pid(0x%04x)." % (id_vendor, id_product))
IOError: [Errno 2] No available device matching vid(0x0fcf) pid(0x1008).
braiden commented 11 years ago

Root cause is probably:

Found device with vid(0x0fcf) pid(0x1008), but interface already claimed.

This means some other software has claimed exclusive access to the USB device. Maybe a kernel driver.

If you were previously a user of "gant", it required to do something like "modprobe usbserial vendor=... product=...", and you may have added a udev rule to make this automatically? If so, make sure no remove the modprobe rule exists in /etc/udev/rules.d and that you "rmmod usbserial" or reboot. If none of this sounds familiar to you than its probably not the cause.

Assuming above doesn't work. What kernel are you running? Stock ubuntu, or something you compiled on your own?

Regards,

On Sun, Jan 6, 2013 at 5:49 PM, Jonathan Zacsh notifications@github.comwrote:

Hey, I'd love to help debug further (looks like maybe I have a totally different issue)

(running Ubuntu 12.04, just plugged the ANT+ USB stick in, nothing else is using it)...

git checkout ioerror ./ant-downloader.py

I get the following:

/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py:36: UserWarning: Module antd was already imported from /home/jzacsh/tmp/gad/python-ant-downloader/antd/init.pyc, but /usr/local/lib/python2.7/dist-packages/python_ant_downloader-12.03.25-py2.7.egg is being added to sys.path import pkg_resources [MainThread] 2013-01-06 17:47:16,390 INFO Found device with vid(0x0fcf) pid(0x1008), but interface already claimed. Traceback (most recent call last): File "./ant-downloader.py", line 3, in downloader() File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/main.py", line 73, in downloader host = antd.cfg.create_antfs_host() File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 112, in create_antfs_host host = antfs.Host(create_ant_session(), keys) File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 99, in create_ant_session session = ant.Session(create_ant_core()) File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 95, in create_ant_core return ant.Core(create_hardware()) File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/cfg.py", line 91, in create_hardware return hw.UsbHardware(id_vendor, id_product, bulk_endpoint) File "/home/jzacsh/tmp/gad/python-ant-downloader/antd/hw.py", line 59, in init raise IOError(errno.ENOENT, "No available device matching vid(0x%04x) pid(0x%04x)." % (id_vendor, id_product)) IOError: [Errno 2] No available device matching vid(0x0fcf) pid(0x1008).

— Reply to this email directly or view it on GitHubhttps://github.com/braiden/python-ant-downloader/issues/11#issuecomment-11936092.

jzacsh commented 11 years ago

Ah, yes the udev rule was my problem. Thanks.

(Note: I have a Garmin 405)

With "Force send" (in Settings > ANT+ > computer) set to "off", I got:

[Thread-1]      2013-01-06 18:33:44,168 WARNING Failed to receive RF beacon at expected period. channel_number=0
[Thread-1]      2013-01-06 18:33:44,177 WARNING Failed to receive RF beacon at expected period. channel_number=0
[Thread-1]      2013-01-06 18:33:44,189 ERROR   USB Serial buffer overflow. PC reading too slow.
[MainThread]    2013-01-06 18:33:54,217 WARNING Retryable error. 5 try(s) remaining. No reply to command. RESET_SYSTEM()
[MainThread]    2013-01-06 18:33:54,244 INFO    Searching for ANT devices.
[MainThread]    2013-01-06 18:33:57,494 DEBUG   Got ANT-FS Beacon. device_number=0xfd87 Beacon{'auth_type': 3, 'pairing_enabled': 0, 'device_state': 0, 'period': 1, 'data_availible': 0, 'descriptor': 131073, 'upload_enabled': 0, 'data_page_id': 67, 'data': '', 'status_2': 0, 'status_1': 9}
[MainThread]    2013-01-06 18:33:57,496 INFO    Found device, but no data availible for download.
[MainThread]    2013-01-06 18:33:58,516 WARNING Failed to cleanup resources on exist.
Traceback (most recent call last):
  File "/home/jzacsh/tmp/python-ant-downloader/antd/main.py", line 115, in downloader
    try: host.close()
  File "/home/jzacsh/tmp/python-ant-downloader/antd/antfs.py", line 241, in close
    self.ant_session.close()
  File "/home/jzacsh/tmp/python-ant-downloader/antd/ant.py", line 568, in close
    assert not self.thread.is_alive()
AssertionError
[Thread-1]      2013-01-06 18:33:58,517 ERROR   Caught Exception handling message, session closing.
Traceback (most recent call last):
  File "/home/jzacsh/tmp/python-ant-downloader/antd/ant.py", line 787, in loop
    for cmd in self.core.recv():
  File "/home/jzacsh/tmp/python-ant-downloader/antd/ant.py", line 519, in recv
    else: raise e
USBError: [Errno 19] No such device (it may have been disconnected)

note: that setting seems to turn it self off every time I the watch goes to sleep

With Force Send turned back on, it works! Full output here: https://gist.github.com/4471080 Note: the Caught Exception on line 197 of that gist is right when my Garmin gave the "transfer successful" message.

braiden commented 11 years ago

Sounds like all is working well then.

The watch will normally only transmit runs that have never been sent to the PC before. You can get around this by doing force send, but you are right, that only works once. Shouldn't matter though: going forward all new runs will be downloaded when you run the program.

Sorry about the exception/error at the end of program (its harmless), i was never able to replicate the issue ioerror branch was supposed to fix, so I haven't cleaned up the code. If it bugs you the master branch should work fine on Ubuntu 12.04.

Regards,

On Sun, Jan 6, 2013 at 6:41 PM, Jonathan Zacsh notifications@github.comwrote:

Ah, yes the udev rule was my problem. Thanks.

(note: i have a garmin 405)

With "Force send" (in settings > ant+ > computer) set to "off", I got:

[Thread-1] 2013-01-06 18:33:44,168 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2013-01-06 18:33:44,177 WARNING Failed to receive RF beacon at expected period. channel_number=0 [Thread-1] 2013-01-06 18:33:44,189 ERROR USB Serial buffer overflow. PC reading too slow. [MainThread] 2013-01-06 18:33:54,217 WARNING Retryable error. 5 try(s) remaining. No reply to command. RESET_SYSTEM() [MainThread] 2013-01-06 18:33:54,244 INFO Searching for ANT devices. [MainThread] 2013-01-06 18:33:57,494 DEBUG Got ANT-FS Beacon. device_number=0xfd87 Beacon{'auth_type': 3, 'pairing_enabled': 0, 'device_state': 0, 'period': 1, 'data_availible': 0, 'descriptor': 131073, 'upload_enabled': 0, 'data_page_id': 67, 'data': '', 'status_2': 0, 'status_1': 9} [MainThread] 2013-01-06 18:33:57,496 INFO Found device, but no data availible for download. [MainThread] 2013-01-06 18:33:58,516 WARNING Failed to cleanup resources on exist. Traceback (most recent call last): File "/home/jzacsh/tmp/python-ant-downloader/antd/main.py", line 115, in downloader try: host.close() File "/home/jzacsh/tmp/python-ant-downloader/antd/antfs.py", line 241, in close self.ant_session.close() File "/home/jzacsh/tmp/python-ant-downloader/antd/ant.py", line 568, in close assert not self.thread.is_alive() AssertionError [Thread-1] 2013-01-06 18:33:58,517 ERROR Caught Exception handling message, session closing. Traceback (most recent call last): File "/home/jzacsh/tmp/python-ant-downloader/antd/ant.py", line 787, in loop for cmd in self.core.recv(): File "/home/jzacsh/tmp/python-ant-downloader/antd/ant.py", line 519, in recv else: raise e USBError: [Errno 19] No such device (it may have been disconnected)

note: that setting seems to turn it self off every time I the watch goes to sleep

With Force Send turned back on, it works! Full output here: https://gist.github.com/4471080

— Reply to this email directly or view it on GitHubhttps://github.com/braiden/python-ant-downloader/issues/11#issuecomment-11936881.

jzacsh commented 11 years ago

Actually this particular branch and this particular attempt was the only one I got to work. I tried this because the master branch was failing to work for me (comments in other bugs). I'm happy to debug that exception for you and help you finish up this branch, if you like. Is that the purpose of this branch? To fix that particular exception?

braiden commented 11 years ago

Sorry for very slow reply. I've tried to cleanup the fix and merge to master. If someone wants to confirm that this is fixed in lastest master I can close this issue.

I'm trying to close out bugs and push a new stable version to pypi.

Basically issues here is: when pyusb raise an IOError it always provides an errno and message. I need to unpack these values from the IOError to know if the error was raised simply due to a timeout. But, on some hardware/software IOError is not un-packable. (I've never been able to create issue on any hardware I have access to.)

I've just updated code to assume that an unpackable IOError is a timeout. This should probably fix the issue, but I can't test. Its a less than perfect solution since there's a chance of the code getting stuck in a loop.

jzacsh commented 11 years ago

Hey braiden, not sure if this is related to this particular bug, so feel free to just put this in another issue:

https://gist.github.com/jzacsh/64c8d4f6f93d23ca2a7e

braiden commented 11 years ago

Moved to #44. If you're still having issues you can reply there:

If you've never paired the watch with the software, the watch needs to be awake. Press a button on the watch to make sure its not sleeping, and run my program again. Once you start downloading the watch should beep and ask you to confirm connection. Once paring is complete, you don't need to wake watch anymore.