Connect to physical ANAFI via Olympe on ubuntu16.04 (installed on vmware) failed

jinwandou · September 9, 2019, 11:27am

i have installed develop environment error return is ReturnTuple(OK=False, f303ed2dd90> socket_fd 21 socket_kind 09/09/2019 04:21:17.133749 _connecting_cb 09/09/2019 04:21:17.135340 _connect_to_device I arsdkctrl_net: Sending json:
I arsdkctrl_net: { “arstream2_client_stream_port”: I arsdkctrl_net: Received json:
I arsdkctrl_net: { “status”: I arsdkctrl_net: Connection refused
09/09/2019 04:21:17.151198 _canceled_cb I arsdkctrl: discovery ‘net’: 09/09/2019 04:21:17.253127 _device_removed_cb I arsdkctrl: discovery ‘net’: stop
09/09/2019 04:21:17.254406 _stop_discovery 09/09/2019 04:21:17.255230 _stop_discovery 09/09/2019 04:21:17.401498 _stop_discovery 09/09/2019 04:21:22.427353 connection ReturnTuple(OK=False, message="Unable 09/09/2019 04:21:22.428461 _stop_discovery 09/09/2019 04:21:22.430248 _destroy_pomp_loop 09/09/2019 04:21:22.436899 _destroy_pomp_loop 09/09/2019 04:21:22.441635 _stop_discovery 09/09/2019 04:21:22.448786 _destroy_net_backend 09/09/2019 04:21:22.450985 _destroy_manager 09/09/2019 04:21:22.453271 destroy_timer 09/09/2019 04:21:22.455408 destroy_timer 09/09/2019 04:21:22.457458 _destroy_pomp_loop : as described on developer website,i try to connect to physical ANAFI by using Olympe (using examples physical_drone.py),but i got errors as following：what should i do ,thank you all.
message="Unable to connect to the device. IP : b’192.168.42.1’ ", value=None, error_code=107)
1 userdate_pointer None
Connecting to device: ANAFI-F024887
Connection in progress…
“55004”, “arstream2_client_control_port”: “55005”, “arstream2_supported_metadata_version”: “1”, “controller_name”: “arsdk-ng”, “controller_type”: “desktop”, “d2c_port”: 9988, “device_id”: “”, “qos_mode”: 0 }
-1, “c2d_port”: 0, “qos_mode”: 0 }
Connection to device: <ctypes.LP_c_char object at 0x7f303ed2dae8> has been canceled for reason: REJECTED
remove device name=‘ANAFI-F024887’ id=‘PI040416AA8F024887’
Device has been removed
Discovery has been stopped
Discovery object has been destroyed
No discovery instance to be stopped
Unable to connect to the device. IP : b’192.168.42.1’
to connect to the device. IP : b’192.168.42.1’ ", value=None, error_code=107)
No discovery instance to be stopped
Pomp loop has been destroyed
Pomp loop has been destroyed
No discovery instance to be stopped
Net backend has been destroyed
Manager has been destroyed
Pomp loop timer has been destroyed
Pomp loop timer has been destroyed
Pomp loop has been destroyed

thank you for any help.

ndessart · September 9, 2019, 12:20pm

Hi,

The “connection has been canceled for reason: REJECTED” error message usually means that another device (usually your SkyController 3 and/or your FreeFlight 6 app) is already connected to the drone. In that case, the drone reject the second SDK connection.

You should check that your SkyController 3 is powered off and that the FreeFlight 6 app is not running on your smartphone (disabling the smartphone wifi should also work) and retry the connection to your Anafi with Olympe. Please let me know if that works for you, thanks!

jinwandou · September 10, 2019, 12:41am

thank you ! but it didn’t work. when i tried as you said,i got error as following:
09/09/2019 17:51:14.924985 _create_pomp_loop Creating pomp loop
09/09/2019 17:51:14.926017 _create_manager New manager has been created!
09/09/2019 17:51:14.926519 _create_manager Manager device callbacks has been added to the manager
09/09/2019 17:51:14.927123 _create_net_backend New net backend has been created
09/09/2019 17:51:14.927615 _create_net_backend Set backend socket callback OK
09/09/2019 17:51:14.928792 _create_pomp_loop Creating pomp loop
09/09/2019 17:51:14.930033 _create_pomp_loop Creating pomp loop
09/09/2019 17:51:14.931135 create_timer Creating pomp timer
09/09/2019 17:51:14.931695 create_timer Creating pomp timer
============connection state = <olympe.arsdkng.drone.Drone object at 0x7f89a708be48>
09/09/2019 17:51:14.933953 _start_net_discovery Net discovery object has been created
I arsdkctrl: discovery ‘net’: start
09/09/2019 17:51:14.935054 _backend_socket_cb backend_pointer <olympe_deps.LP_struct_arsdkctrl_backend_net object at 0x7f898f0277b8> socket_fd 17 socket_kind 0 userdate_pointer None
09/09/2019 17:51:14.935902 _start_net_discovery Net discovery has been started
I arsdkctrl: discovery ‘net’: add device name=‘ANAFI-F024887’ id=‘PI040416AA8F024887’
09/09/2019 17:51:14.966997 _device_added_cb New device has been detected
09/09/2019 17:51:14.967735 _device_added_cb {‘state’: 0}
09/09/2019 17:51:14.968842 _backend_socket_cb backend_pointer <olympe_deps.LP_struct_arsdkctrl_backend_net object at 0x7f898d7ebd90> socket_fd 20 socket_kind 2 userdate_pointer None
09/09/2019 17:51:14.969668 _backend_socket_cb backend_pointer <olympe_deps.LP_struct_arsdkctrl_backend_net object at 0x7f898d7ebd90> socket_fd 21 socket_kind 1 userdate_pointer None
09/09/2019 17:51:14.970769 _connecting_cb Connecting to device: ANAFI-F024887
09/09/2019 17:51:14.971424 _connect_to_device Connection in progress…
I arsdkctrl_net: Sending json:
I arsdkctrl_net: { “arstream2_client_stream_port”: “55004”, “arstream2_client_control_port”: “55005”, “arstream2_supported_metadata_version”: “1”, “controller_name”: “arsdk-ng”, “controller_type”: “desktop”, “d2c_port”: 9988, “device_id”: “”, “qos_mode”: 0 }
I arsdkctrl_net: Received json:
I arsdkctrl_net: { “c2d_update_port”: 51, “c2d_user_port”: 21, “status”: 0, “c2d_port”: 2233, “qos_mode”: 0 }
09/09/2019 17:51:14.989688 _connected_cb Connected to device: ANAFI-F024887
09/09/2019 17:51:14.990670 _connected_cb {‘json’: {‘c2d_port’: 2233,
‘c2d_update_port’: 51,
‘c2d_user_port’: 21,
‘qos_mode’: 0,
‘status’: 0},
‘state’: 0}
09/09/2019 17:51:14.991446 _create_command_interface Command interface has been created: itf=<olympe_deps.LP_struct_arsdk_cmd_itf object at 0x7f898d7ebc80>
W arsdk: net ping failures: 1
W arsdk: net ping failures: 2
W arsdk: net ping failures: 3
E arsdk: net Too many ping failures
09/09/2019 17:51:22.993385 _link_status_cb Link status: 0
I arsdkctrl: discovery ‘net’: remove device name=‘ANAFI-F024887’ id=‘PI040416AA8F024887’
I arsdkctrl: internally disconnect device name=‘ANAFI-F024887’ type=ANAFI4K id=‘PI040416AA8F024887’
09/09/2019 17:51:22.996017 _disconnected_cb Disconnected from device: ANAFI-F024887
09/09/2019 17:51:22.999244 _device_removed_cb Device has been removed
I arsdkctrl: discovery ‘net’: stop
09/09/2019 17:51:23.002004 _stop_discovery Discovery has been stopped
09/09/2019 17:51:23.003869 _stop_discovery Discovery object has been destroyed
09/09/2019 17:51:23.016563 _stop_discovery No discovery instance to be stopped
09/09/2019 17:51:23.020967 _stop_discovery No discovery instance to be stopped
09/09/2019 17:51:23.032161 _send_command Common.Common.AllStates: Warning some callbacks weren’t called: common.CommonState.AllStatesChanged()

Traceback (most recent call last):
File “/home/hujun/code/parrot-groundsdk/packages/olympe/src/olympe/_private/pomp_loop_thread.py”, line 211, in _run_task_list
ret = f(*args, **kwargs)
File “/home/hujun/code/parrot-groundsdk/packages/olympe/src/olympe/arsdkng/drone.py”, line 871, in _send_command_impl
raise RuntimeError("[sendcmd] Error cmd interface seems to be destroyed")
RuntimeError: [sendcmd] Error cmd interface seems to be destroyed
09/09/2019 17:51:33.051660 _send_command Common.Settings.AllSettings: Warning some callbacks weren’t called: common.SettingsState.AllSettingsChanged()

ReturnTuple(OK=False, message=“Cannot get states info b’192.168.42.1’”, value=None, error_code=107)
09/09/2019 17:51:33.060069 _destroy_pomp_loop Pomp loop has been destroyed
09/09/2019 17:51:33.087584 _destroy_pomp_loop Pomp loop has been destroyed
09/09/2019 17:51:33.088576 _stop_discovery No discovery instance to be stopped
09/09/2019 17:51:33.097646 _destroy_net_backend Net backend has been destroyed
09/09/2019 17:51:33.098905 _destroy_manager Manager has been destroyed
09/09/2019 17:51:33.100201 destroy_timer Pomp loop timer has been destroyed
09/09/2019 17:51:33.101514 destroy_timer Pomp loop timer has been destroyed
09/09/2019 17:51:33.102566 _destroy_pomp_loop Pomp loop has been destroyed

ndessart · September 10, 2019, 10:18am

Can you please increase the loglevel and share the new log?

drone = olympe.Drone("192.168.42.1", loglevel=7)

Thanks

jinwandou · September 11, 2019, 12:27am

the new log is as follows,
thanks !

10/09/2019 17:35:50.913752 _create_pomp_loop Creating pomp loop
10/09/2019 17:35:50.915368 _create_manager New manager has been created!
10/09/2019 17:35:50.916395 _create_manager Manager device callbacks has been added to the manager
10/09/2019 17:35:50.962095 _create_net_backend New net backend has been created
10/09/2019 17:35:50.962994 _create_net_backend Set backend socket callback OK
10/09/2019 17:35:50.983052 _create_pomp_loop Creating pomp loop
10/09/2019 17:35:50.985285 _create_pomp_loop Creating pomp loop
10/09/2019 17:35:51.058159 create_timer Creating pomp timer
10/09/2019 17:35:51.059284 create_timer Creating pomp timer
10/09/2019 17:35:51.330134 _start_net_discovery Net discovery object has been created
I arsdkctrl: discovery ‘net’: start
10/09/2019 17:35:51.334577 _backend_socket_cb backend_pointer <olympe_deps.LP_struct_arsdkctrl_backend_net object at 0x7f2a9bf8e7b8> socket_fd 17 socket_kind 0 userdate_pointer None
10/09/2019 17:35:51.387840 _start_net_discovery Net discovery has been started
I arsdkctrl: discovery ‘net’: add device name=‘ANAFI-F024867’ id=‘PI040416AA8F024867’
10/09/2019 17:35:51.620939 _device_added_cb New device has been detected
10/09/2019 17:35:51.622076 _device_added_cb {‘state’: 0}
10/09/2019 17:35:51.624071 _backend_socket_cb backend_pointer <olympe_deps.LP_struct_arsdkctrl_backend_net object at 0x7f2a9a752d90> socket_fd 20 socket_kind 2 userdate_pointer None
10/09/2019 17:35:51.625540 _backend_socket_cb backend_pointer <olympe_deps.LP_struct_arsdkctrl_backend_net object at 0x7f2a9a752d90> socket_fd 21 socket_kind 1 userdate_pointer None
10/09/2019 17:35:51.632723 _connecting_cb Connecting to device: ANAFI-F024867
10/09/2019 17:35:51.633881 _connect_to_device Connection in progress…
I arsdkctrl_net: Sending json:
I arsdkctrl_net: { “arstream2_client_stream_port”: “55004”, “arstream2_client_control_port”: “55005”, “arstream2_supported_metadata_version”: “1”, “controller_name”: “arsdk-ng”, “controller_type”: “desktop”, “d2c_port”: 9988, “device_id”: “”, “qos_mode”: 0 }
I arsdkctrl_net: Received json:
I arsdkctrl_net: { “c2d_update_port”: 51, “c2d_user_port”: 21, “status”: 0, “c2d_port”: 2233, “qos_mode”: 0 }
10/09/2019 17:35:51.714437 _connected_cb Connected to device: ANAFI-F024867
10/09/2019 17:35:51.717831 _connected_cb {‘json’: {‘c2d_port’: 2233,
‘c2d_update_port’: 51,
‘c2d_user_port’: 21,
‘qos_mode’: 0,
‘status’: 0},
‘state’: 0}
10/09/2019 17:35:51.719221 _create_command_interface Command interface has been created: itf=<olympe_deps.LP_struct_arsdk_cmd_itf object at 0x7f2a9a752b70>
10/09/2019 17:35:51.807821 _send_command_impl Command g_arsdk_cmd_desc_Common_Common_AllStates has been encoded
10/09/2019 17:35:51.813105 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:51.814213 _send_command_impl Command g_arsdk_cmd_desc_Common_Common_AllStates has been sent to the drone with arg ()
10/09/2019 17:35:51.977069 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:52.144460 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:52.300064 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:52.470719 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:52.624949 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:52.789691 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:52.958522 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:53.168275 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:53.321357 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:53.504317 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:53.668754 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:53.821393 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:53.991940 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:54.155090 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:54.368463 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:54.520583 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:54.705745 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:54.867322 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:55.042116 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:55.211043 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:55.364636 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:55.525672 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:55.679405 _cmd_itf_send_status_cb Command send status: 0, done: 0
W arsdk: net ping failures: 1
10/09/2019 17:35:55.832941 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:55.992092 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:56.145395 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:56.360639 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:56.528022 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:56.706313 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:56.861952 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:57.029320 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:57.194545 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:57.403818 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:57.558160 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:57.711246 _cmd_itf_send_status_cb Command send status: 0, done: 0
W arsdk: net ping failures: 2
10/09/2019 17:35:57.866617 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:58.027551 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:58.190571 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:58.346805 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:58.523443 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:58.684521 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:58.877552 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:59.080977 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:59.234928 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:59.388099 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:59.544240 _cmd_itf_send_status_cb Command send status: 0, done: 0
10/09/2019 17:35:59.703013 _cmd_itf_send_status_cb Command send status: 0, done: 0
W arsdk: net ping failures: 3
E arsdk: net Too many ping failures
10/09/2019 17:35:59.716112 _link_status_cb Link status: 0
I arsdkctrl: discovery ‘net’: remove device name=‘ANAFI-F024867’ id=‘PI040416AA8F024867’
I arsdkctrl: internally disconnect device name=‘ANAFI-F024867’ type=ANAFI4K id=‘PI040416AA8F024867’
10/09/2019 17:35:59.718805 _cmd_itf_send_status_cb Command send status: 3, done: 1
10/09/2019 17:35:59.721072 _disconnected_cb Disconnected from device: ANAFI-F024867
10/09/2019 17:35:59.723237 _dispose_cmd_cb Dispose command received
10/09/2019 17:35:59.725580 _device_removed_cb Device has been removed
I arsdkctrl: discovery ‘net’: stop
10/09/2019 17:35:59.727972 _stop_discovery Discovery has been stopped
10/09/2019 17:35:59.731058 _stop_discovery Discovery object has been destroyed
10/09/2019 17:35:59.820583 _stop_discovery No discovery instance to be stopped
10/09/2019 17:35:59.823105 _stop_discovery No discovery instance to be stopped
10/09/2019 17:35:59.834962 _send_command Common.Common.AllStates: Warning some callbacks weren’t called: common.CommonState.AllStatesChanged()

10/09/2019 17:35:59.843120 _send_command_impl Command g_arsdk_cmd_desc_Common_Settings_AllSettings has been encoded
Traceback (most recent call last):
File “/home/hujun/code/parrot-groundsdk/packages/olympe/src/olympe/_private/pomp_loop_thread.py”, line 211, in _run_task_list
ret = f(*args, **kwargs)
File “/home/hujun/code/parrot-groundsdk/packages/olympe/src/olympe/arsdkng/drone.py”, line 871, in _send_command_impl
raise RuntimeError("[sendcmd] Error cmd interface seems to be destroyed")
RuntimeError: [sendcmd] Error cmd interface seems to be destroyed
10/09/2019 17:36:09.854541 _send_command Common.Settings.AllSettings: Warning some callbacks weren’t called: common.SettingsState.AllSettingsChanged()

ReturnTuple(OK=False, message=“Cannot get states info b’192.168.42.1’”, value=None, error_code=107)
10/09/2019 17:36:09.874121 _destroy_pomp_loop Pomp loop has been destroyed
10/09/2019 17:36:09.876851 _destroy_pomp_loop Pomp loop has been destroyed
10/09/2019 17:36:09.878918 _stop_discovery No discovery instance to be stopped
10/09/2019 17:36:09.924203 _destroy_net_backend Net backend has been destroyed
10/09/2019 17:36:09.925108 _destroy_manager Manager has been destroyed
10/09/2019 17:36:09.925919 destroy_timer Pomp loop timer has been destroyed
10/09/2019 17:36:09.926720 destroy_timer Pomp loop timer has been destroyed

ndessart · September 11, 2019, 7:25am

OK, something is definitely wrong here. I’ve recently seen this kind of logs from a user running Olympe from a VirtualBox VM on a Mac.

I can’t explain nor reproduce this issue. Somehow your test environment reveals this connectivity issue. I haven’t been able to reproduce with a VirtualBox 5.12 VM running Ubuntu 16.04 on a Ubuntu 18.04 host. I am using a TP-Link -TL-WN722N usb wifi adapter shared with the VM.

Could you please share your exact system configuration :

host OS version
VMware Player/Workstation you are using
wifi hardware reference (integrated intel wifi chipset / usb wifi adapter)

Thanks

jinwandou · September 11, 2019, 7:56am

Thanks for your reply, the host OS version of my laptop is win7 ,with VMware Workstation 14 pro running Ubuntu 16.04,wifi hardware is integrated wifi chipset, and another computer host OS version is win10, and with VMware Workstation running Ubuntu 16.04, its wifi hardware is TP-Link -TL-WN726N, both two machines get the same error.

jinwandou · September 11, 2019, 10:12am

I am glad to tell you it worked. the reson may be the VMware Workstation setting is NAT mode, should set to bridge mode.
Thanks

ndessart · September 11, 2019, 10:21am

Great! Could you please share a couple of screenshots showing the relevant settings in VMware Workstation UI? That would be very helpful! Thanks