Sphinx - Received an error from firmwared:This operation is not permitted from the current state

pixelblade · January 29, 2023, 3:44pm

Hi there,

I have just installed both Olympe and Sphinx but I am having problem with the latter.

System:
Ubuntu 20.04
CPU: 2X Xeon E5-2620 3v
GPU: 1660 Super
RAM: 16gb

To reproduce the error:
In the first terminal I launch the application using the command:

<pre>
sphinx &quot;/opt/parrot-sphinx/usr/share/sphinx/drones/anafi_ai.drone&quot;::firmware=&quot;/path/to/file/anafi2-pc.ext2.zip&quot;
</pre>

in the second terminal:

parrot-ue4-empty

I can se the window opening and the drone get displayed as expected, but after few seconds the drone disappears and the app crash. The output from the first terminal is:

Parrot-Sphinx simulator version 2.10.3

Connecting to firmwared version: 2.10.3
starting Telemetryd to capture true data...
Gazebo multi-robot simulator, version 11.11.0
Copyright (C) 2012 Open Source Robotics Foundation.
Released under the Apache 2 License.
http://gazebosim.org

[Msg] Waiting for master.
[Msg] Connected to gazebo master @ http://127.0.0.1:11345
[Msg] Publicized address: 127.0.0.1
[Msg] Loading world file [/tmp/sphinx-1b7dd903.world]
[Msg] created parameter server on http:8383
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] World params for 'default':
[Msg] 	spawn_points:
[Msg] 		- name: default
[Msg] 		  pose: -3.3 -0 0.4 0 -0 0
[Msg] connected to firmwared
[Msg] Preparation of firmware /path/to/file/anafi2-pc.ext2.zip
[Msg] firmware /usr/share/firmwared/firmwares//anafi2-pc.ext2.zip.52241fcc-d04a-4b92-9fdf-f2f6bd01eced.firmware supported hardwares: 
[Msg] 	anafi2
[Msg] All firmwares prepared.
[Msg] Selected params to build .sdf file for anafi_ai:
	param drone_iface = eth0
	param drone_ip = 192.168.42.1/24
	param full_simclock = 1
	param hil_ip = auto
	param invisible_body = 0
	param photo_high_res = 0
	param sdcard_serial = __undefined__
	param virt_iface = eth1
	param with_depth_hil = 0
	param with_external_cams = 1
	param with_front_cam = 1
	param with_front_stcam = 1
	param with_gimbal = 1
	param with_tracking_hil = 0
	param xmlns = 
[Msg] Drone anafi_ai[7aeb647070bbc15ee5f0c8cad82d0596c0c8b5ec] loaded
[Wrn] [Machine.cc:1348] Wireless interface 'wlx1cbfcedcc5b9' is already being used. Sphinx will now take over.
[Msg] Using Wifi interface 'wlx1cbfcedcc5b9'
[Msg] Web dashboard is accessible at http://localhost:9002
[Err] [PompPool.hh:143] Received an error from firmwared: This operation is not permitted from the current state.
[Err] [AutomatonStartInst.cc:263] Failed to start anafi_ai
[Msg] Instance anafi_ai[7aeb647070bbc15ee5f0c8cad82d0596c0c8b5ec] dropped.
[Msg] Firmware anafi_ai[d182fc04b2a9ebf1ad4f9be8fc60734eefa9f3aa] unprepared.
[Msg] All drones dropped. Let's shut down.

here snapshot from the same output:

and from the second:

chmod: changing permissions of '/opt/parrot-ue4-empty/Parrot-Sphinx-App/Binaries/Linux/UnrealApp': Operation not permitted
Increasing per-process limit of core file size to infinity.
LogInit: LLM is enabled
LogInit: LLM CsvWriter: off TraceWriter: off
LogPakFile: Display: Found Pak file ../../../Parrot-Sphinx-App/Content/Paks/Parrot-Sphinx-App-LinuxNoEditor.pak attempting to mount.
LogPakFile: Display: Mounting pak file ../../../Parrot-Sphinx-App/Content/Paks/Parrot-Sphinx-App-LinuxNoEditor.pak.
LogPakFile: PakFile PrimaryIndexSize=28554
LogPakFile: PakFile PathHashIndexSize=56040
LogPakFile: PakFile FullDirectoryIndexSize=81375
LogPakFile: OnPakFileMounted2Time == 0.000001
LogPlatformFile: Using cached read wrapper
LogTaskGraph: Started task graph with 5 named threads and 17 total threads with 3 sets of task threads.
LogStats: Stats thread started at 0.072457
LogICUInternationalization: ICU TimeZone Detection - Raw Offset: +0:00, Platform Override: ''
LogUnixPlatformFile: Warning: create dir('/opt/parrot-ue4-empty/Parrot-Sphinx-App/Saved/Config/CrashReportClient/UE4CC-Linux-95346EA5F93348F8BFA69D52FFE266D9/CrashReportClient.ini') failed: errno=13 (Permission denied)
LogUnixPlatformFile: Warning: create dir('/opt/parrot-ue4-empty/Parrot-Sphinx-App/Saved/Config/CrashReportClient/UE4CC-Linux-95346EA5F93348F8BFA69D52FFE266D9/CrashReportClient.ini') failed: errno=13 (Permission denied)
LogPluginManager: Mounting plugin GzUE4Bridge
LogPluginManager: Mounting plugin PhysXVehicles
LogPluginManager: Mounting plugin ImGui
LogPluginManager: Mounting plugin ProceduralMeshComponent
LogPluginManager: Mounting plugin LensDistortion
LogPluginManager: Mounting plugin ActorMovementSystem
LogPluginManager: Mounting plugin Paper2D
LogPluginManager: Mounting plugin AISupport
LogPluginManager: Mounting plugin EnvironmentQueryEditor
LogPluginManager: Mounting plugin LightPropagationVolume
LogPluginManager: Mounting plugin AnimationSharing
LogPluginManager: Mounting plugin SignificanceManager
LogPluginManager: Mounting plugin PluginUtils
LogPluginManager: Mounting plugin PropertyAccessEditor
LogPluginManager: Mounting plugin UObjectPlugin
LogPluginManager: Mounting plugin AssetManagerEditor
LogPluginManager: Mounting plugin GeometryMode
LogPluginManager: Mounting plugin DatasmithContent
LogPluginManager: Mounting plugin VariantManagerContent
LogPluginManager: Mounting plugin AlembicImporter
LogPluginManager: Mounting plugin GeometryCache
LogPluginManager: Mounting plugin AutomationUtils
LogPluginManager: Mounting plugin ScreenshotTools
LogPluginManager: Mounting plugin BackChannel
LogPluginManager: Mounting plugin GeometryProcessing
LogPluginManager: Mounting plugin EditableMesh
LogPluginManager: Mounting plugin ChaosSolverPlugin
LogPluginManager: Mounting plugin ChaosNiagara
LogPluginManager: Mounting plugin Niagara
LogPluginManager: Mounting plugin CharacterAI
LogPluginManager: Mounting plugin MotoSynth
LogPluginManager: Mounting plugin PlatformCrypto
LogPluginManager: Mounting plugin ImgMedia
LogPluginManager: Mounting plugin MediaCompositing
LogPluginManager: Mounting plugin MeshPainting
LogPluginManager: Mounting plugin TcpMessaging
LogPluginManager: Mounting plugin UdpMessaging
LogPluginManager: Mounting plugin ActorSequence
LogPluginManager: Mounting plugin LevelSequenceEditor
LogPluginManager: Mounting plugin MatineeToLevelSequence
LogPluginManager: Mounting plugin TemplateSequence
LogPluginManager: Mounting plugin OnlineSubsystem
LogPluginManager: Mounting plugin OnlineSubsystemNull
LogPluginManager: Mounting plugin OnlineSubsystemUtils
LogPluginManager: Mounting plugin LauncherChunkInstaller
LogPluginManager: Mounting plugin ActorLayerUtilities
LogPluginManager: Mounting plugin ArchVisCharacter
LogPluginManager: Mounting plugin AssetTags
LogPluginManager: Mounting plugin CableComponent
LogPluginManager: Mounting plugin ChunkDownloader
LogPluginManager: Mounting plugin CustomMeshComponent
LogPluginManager: Mounting plugin LinuxDeviceProfileSelector
LogPluginManager: Mounting plugin LocationServicesBPLibrary
LogPluginManager: Mounting plugin MobilePatchingUtils
LogPluginManager: Mounting plugin RuntimePhysXCooking
[2023.01.29-15.34.55:555][  0]LogAMS: Display: Starting up ActorMovementSystem
[2023.01.29-15.34.55:886][  0]LogGzUE4: Display: Loaded settings from: /home/scorp6/.parrot-sphinx/gzue4/settings.json
[2023.01.29-15.34.55:886][  0]LogGzUE4: Display: Using QWERTY keyboard layout
[2023.01.29-15.34.55:891][  0]LogApp: Display: Loading default level: 'main'
[2023.01.29-15.34.56:075][  0]LogGzUE4: Display: Waiting for Sphinx...
[2023.01.29-15.34.56:185][  4]LogGzUE4: Display: Client got connected
[2023.01.29-15.34.57:242][ 66]LogAMS: Display: Client connected
[2023.01.29-15.35.04:342][492]LogGzUE4: Display: Client got connected
[2023.01.29-15.35.10:087][816]LogGzUE4: Display: Client got disconnected
[2023.01.29-15.35.12:442][837]LogGzUE4: Display: Client got disconnected
[2023.01.29-15.35.12:442][837]LogAMS: Display: Client disconnected
[2023.01.29-15.35.12:442][837]LogGzUE4: Error: Waited too long to get tick request
[2023.01.29-15.35.12:463][838]LogGzUE4: Display: Waiting for Sphinx...

It looks like there are some permission problems but I cannot figure out how to resolve it.
The firmware is the most recent one and I tried with different zip files (anafi, anafi2)
Any suggestions?

Thanks for your time

ocrave · January 30, 2023, 8:30am

Hello,

It seems that Sphinx steals an already used wireless interface. Can you test with the drone option wifi_iface= to make sure that it is not what is causing this issue?

sphinx "/opt/parrot-sphinx/usr/share/sphinx/drones/anafi_ai.drone"::firmware="/path/to/file/anafi2-pc.ext2.zip"::wifi_iface=

Can you also show us the logs from firmwared? The procedure to see the logs is described here: How to debug firmwares - 2.10.3

You need to start ulogcat to see the logs from firmwared:

sudo ulogcat -v long -C

You may also want to clear the cache of firmwared to make sure that there is no corrupted firmware already installed:

fdc drop_all firmwares

pixelblade · January 30, 2023, 9:35pm

Thanks for the prompt reply,

So… I tried to follow the suggestions, in th first terminal:

sudo systemctl start firmwared.service
sphinx "/opt/parrot-sphinx/usr/share/sphinx/drones/anafi_ai.drone"::firmware="/path/to//anafi2-pc.ext2.zip"::wifi_iface=

output:

Parrot-Sphinx simulator version 2.10.3

Connecting to firmwared version: 2.10.3
starting Telemetryd to capture true data...
Gazebo multi-robot simulator, version 11.11.0
Copyright (C) 2012 Open Source Robotics Foundation.
Released under the Apache 2 License.
http://gazebosim.org

[Msg] Waiting for master.
[Msg] Connected to gazebo master @ http://127.0.0.1:11346
[Msg] Publicized address: 127.0.0.1
[Msg] Loading world file [/tmp/sphinx-95853532.world]
[Msg] created parameter server on http:8384
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] Waiting for a UE4 application to start...
[Msg] World params for 'default':
[Msg] 	spawn_points:
[Msg] 		- name: default
[Msg] 		  pose: -3.3 -0 0.4 0 -0 0
[Msg] connected to firmwared
[Msg] Preparation of firmware /home/scorp6/Downloads/anafi2-pc.ext2.zip
[Msg] firmware /usr/share/firmwared/firmwares//anafi2-pc.ext2.zip.52241fcc-d04a-4b92-9fdf-f2f6bd01eced.firmware supported hardwares: 
[Msg] 	anafi2
[Msg] All firmwares prepared.
[Msg] Selected params to build .sdf file for anafi_ai:
	param drone_iface = eth0
	param drone_ip = 192.168.42.1/24
	param full_simclock = 1
	param hil_ip = auto
	param invisible_body = 0
	param photo_high_res = 0
	param sdcard_serial = __undefined__
	param virt_iface = eth1
	param with_depth_hil = 0
	param with_external_cams = 1
	param with_front_cam = 1
	param with_front_stcam = 1
	param with_gimbal = 1
	param with_tracking_hil = 0
	param xmlns = 
[Msg] Drone anafi_ai[58831b355996e12c43eac1f90ce7d906ff833832] loaded
[Msg] No wifi interface will be used.
[Msg] Web dashboard is accessible at http://localhost:9003
[Err] [StateMonitoring.cc:68] Failed to detect landing after 10 seconds.
[Msg] Instance adamant_adelina[58831b355996e12c43eac1f90ce7d906ff833832] restarted
[Msg] All drones instantiated

snapshot:

while on the other terminal (parrot-ue4-empty) the output is:

[2023.01.30-21.12.28:156][  0]LogAMS: Display: Starting up ActorMovementSystem
[2023.01.30-21.12.28:460][  0]LogGzUE4: Display: Loaded settings from: /home/scorp6/.parrot-sphinx/gzue4/settings.json
[2023.01.30-21.12.28:460][  0]LogGzUE4: Display: Using QWERTY keyboard layout
[2023.01.30-21.12.28:463][  0]LogApp: Display: Loading default level: 'main'
[2023.01.30-21.12.28:655][  0]LogGzUE4: Display: Waiting for Sphinx...
[2023.01.30-21.12.29:280][ 34]LogGzUE4: Display: Client got connected
[2023.01.30-21.12.30:430][103]LogAMS: Display: Client connected
[2023.01.30-21.12.32:247][212]LogGzUE4: Display: Client got connected
[2023.01.30-21.12.32:597][232]LogGzUE4: Error: Failed to send request to Json-RPC server: Exception -32003 : Client connector error: libcurl error: 7 -> Could not connect to http://localhost:8383
[2023.01.30-21.12.32:597][232]LogGzUE4: Error: Failed to retrieve machine info: anafi_ai

I then cleared the cache fdc drop_all firmwares and ran the sudo ulogcat -v long -C.
the output from the latter is (NOTE, the output it is too large to be posted in the forum, I hit the caracther limit, so I added only the last part, let me know if I can send it in another way):

1-02 03:00:41.215 I telemetryd  (telemetryd-21275)               : sighandler: signo=2(Interrupt)
01-02 03:00:41.223 I telemetryd  (telemetryd-21275)               : Exiting loop
01-02 03:00:41.235 I tlmblackbox (telemetryd-21275)               : Closing file './sphinx-11346-log.tlmb'
01-02 03:00:41.235 I telemetryd  (telemetryd-21275)               : Unloading '/opt/parrot-sphinx/usr/bin/../lib/tlm-plugins//tlm-blackbox.so'
01-02 03:00:41.235 I telemetryd  (telemetryd-21275)               : Unloading '/opt/parrot-sphinx/usr/bin/../lib/tlm-plugins//tlm-gndctrl.so'
01-02 03:00:41.236 I telemetryd  (telemetryd-21275)               : sighandler: signo=15(Terminated)
01-02 03:00:41.238 I shd         (gzserver-21331/gz_world_update-21396): omniscient___UE4__magic_tile: closed
01-02 03:00:41.243 I firmwared_instances(firmwared-13387)         : instance_exec: sprop dragonfly.services stop.
01-01 01:07:53.318 E shd         (imu2quat-304)                   : vcam@imu: bad ts order detected: 0.000000000 <= 0.000000000
01-01 01:07:53.318 E libtelemetry(imu2quat-304)                   : write:97: shd_write err=14(Bad address)
01-01 01:07:53.318 E libtelemetry(imu2quat-304)                   : putSample:323: ProducerCb::write err=14(Bad address)
01-01 01:07:53.318 E imu2quat_calc(imu2quat-304)                  : quat_push_process:367: failed to push quaternion err=14(Bad address)
01-02 03:00:41.255 N boxinit     (boxinit-22155)                  : service 'publisherd' pid 282 is being killed
01-02 03:00:41.255 N boxinit     (boxinit-22155)                  : service 'publisherd-itl' pid 283 is being killed
01-02 03:00:41.255 N boxinit     (boxinit-22155)                  : service 'controld' pid 284 is being killed
01-02 03:00:41.255 N boxinit     (boxinit-22155)                  : service 'drone-controller' pid 285 is being killed
01-01 01:07:53.327 I loggerd     (loggerd-224)                    : flushing and syncing, reason: init.svc.guidance=stopped
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : service 'stream-server' pid 287 is being killed
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : process 'publisherd', pid 282 exited
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : process 'publisherd' killing any children in process group
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : process 'publisherd-itl', pid 283 exited
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : process 'publisherd-itl' killing any children in process group
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : service 'vacq-front' pid 286 is being killed
01-02 03:00:41.256 N boxinit     (boxinit-22155)                  : service 'pimp-hovering' pid 288 is being killed
01-02 03:00:41.257 N boxinit     (boxinit-22155)                  : service 'pimp-vision' pid 289 is being killed
01-02 03:00:41.257 N boxinit     (boxinit-22155)                  : service 'pimp-media' pid 290 is being killed
01-02 03:00:41.257 N boxinit     (boxinit-22155)                  : service 'gpsd' pid 291 is being killed
01-02 03:00:41.257 N boxinit     (boxinit-22155)                  : service 'webserverd' pid 292 is being killed
01-02 03:00:41.257 N boxinit     (boxinit-22155)                  : service 'soundd' pid 294 is being killed
01-02 03:00:41.257 N boxinit     (boxinit-22155)                  : service 'ephemeris-wd' pid 295 is being killed
01-01 01:07:53.329 I control_clt (drid-300)                       : Disconnected from control
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : service 'fdr-lited' pid 305 is being killed
01-01 01:07:53.329 I fcrd        (fcrd-303)                       : Disconnected
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : service 'gimbald-fstcam' pid 310 is being killed
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : service 'gimbald-fcam' pid 308 is being killed
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : service 'fov-fcam' pid 307 is being killed
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : process 'controld', pid 284 exited
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : process 'controld' killing any children in process group
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : process 'gpsd', pid 291 exited
01-02 03:00:41.258 N boxinit     (boxinit-22155)                  : process 'gpsd' killing any children in process group
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : process 'vacq-front', pid 286 exited
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : process 'vacq-front' killing any children in process group
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : process 'soundd', pid 294 exited
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : process 'soundd' killing any children in process group
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'juno-pilot' pid 297 is being killed
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'juno-target' pid 298 is being killed
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'rhllor' pid 299 is being killed
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'drid' pid 300 is being killed
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'fourasd' pid 301 is being killed
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'fake-sensors' pid 302 is being killed
01-02 03:00:41.259 N boxinit     (boxinit-22155)                  : service 'fcrd' pid 303 is being killed
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : service 'refactoryd' pid 309 is being killed
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : service 'imu2quatd' pid 304 is being killed
01-02 03:00:41.260 E pomp        (gzserver-21331/fwman_io_loop-21401): recvmsg(fd=315) err=104(Connection reset by peer)
01-02 03:00:41.260 E pomp        (gzserver-21331/fwman_io_loop-21401): recvmsg(fd=316) err=104(Connection reset by peer)
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : process 'gimbald-fcam', pid 308 exited
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : process 'gimbald-fcam' killing any children in process group
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : process 'gimbald-fstcam', pid 310 exited
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : process 'gimbald-fstcam' killing any children in process group
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : process 'rhllor', pid 299 exited
01-02 03:00:41.260 N boxinit     (boxinit-22155)                  : process 'rhllor' killing any children in process group
01-02 03:00:41.261 W vipcs       (gzserver-21331/vertical_camera-22028): vcam.sock:condor-src:src-531/vcam_raw Started remote disconnected.
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'drid', pid 300 exited
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'drid' killing any children in process group
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'juno-pilot', pid 297 exited
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'juno-pilot' killing any children in process group
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'fourasd', pid 301 exited
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'fourasd' killing any children in process group
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'juno-target', pid 298 exited
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'juno-target' killing any children in process group
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'pimp-vision', pid 289 exited
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'pimp-vision' killing any children in process group
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'pimp-hovering', pid 288 exited
01-02 03:00:41.261 N boxinit     (boxinit-22155)                  : process 'pimp-hovering' killing any children in process group
01-02 03:00:41.262 N boxinit     (boxinit-22155)                  : process 'pimp-media', pid 290 exited
01-02 03:00:41.262 N boxinit     (boxinit-22155)                  : process 'pimp-media' killing any children in process group
01-02 03:00:41.263 N boxinit     (boxinit-22155)                  : process 'imu2quatd', pid 304 exited
01-02 03:00:41.263 N boxinit     (boxinit-22155)                  : process 'imu2quatd' killing any children in process group
01-02 03:00:41.264 N boxinit     (boxinit-22155)                  : process 'fake-sensors', pid 302 exited
01-02 03:00:41.264 N boxinit     (boxinit-22155)                  : process 'fake-sensors' killing any children in process group
01-02 03:00:41.264 N boxinit     (boxinit-22155)                  : process 'fov-fcam', pid 307 exited
01-02 03:00:41.264 N boxinit     (boxinit-22155)                  : process 'fov-fcam' killing any children in process group
01-02 03:00:41.265 N boxinit     (boxinit-22155)                  : process 'refactoryd', pid 309 exited
01-02 03:00:41.265 N boxinit     (boxinit-22155)                  : process 'refactoryd' killing any children in process group
01-02 03:00:41.267 N boxinit     (boxinit-22155)                  : process 'stream-server', pid 287 exited
01-02 03:00:41.267 N boxinit     (boxinit-22155)                  : process 'stream-server' killing any children in process group
01-02 03:00:41.269 E pomp        (gzserver-21331/fwman_io_loop-21401): recvmsg(fd=314) err=104(Connection reset by peer)
01-02 03:00:41.269 N boxinit     (boxinit-22155)                  : process 'drone-controller', pid 285 exited
01-02 03:00:41.270 N boxinit     (boxinit-22155)                  : process 'drone-controller' killing any children in process group
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'ephemeris-wd', pid 295 exited
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'ephemeris-wd' killing any children in process group
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'fcrd', pid 303 exited
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'fcrd' killing any children in process group
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'fdr-lited', pid 305 exited
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'fdr-lited' killing any children in process group
01-01 01:07:53.338 I loggerd     (loggerd-224)                    : flushing and syncing, reason: init.svc.fsup=stopped
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'webserverd', pid 292 exited
01-02 03:00:41.277 N boxinit     (boxinit-22155)                  : process 'webserverd' killing any children in process group
01-01 01:07:53.339 I loggerd     (loggerd-224)                    : flushing and syncing, reason: init.svc.drone-controller=stopped
01-02 03:00:42.700 I telemetryd  (telemetryd-21275)               : Unloading '/opt/parrot-sphinx/usr/bin/../lib/tlm-plugins//tlm-web-gndctrl.so'
01-02 03:00:42.700 E pomp        (telemetryd-21275)               : fd=15, cb=0x7f1e7bc3d160 still in loop
01-02 03:00:42.700 E telemetryd  (telemetryd-21275)               : pomp_loop_destroy: err=-16(Device or resource busy)
01-02 03:00:42.700 I shd         (telemetryd-21275)               : omniscient___UE4__magic_tile: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : wind: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : userscript_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : imu_flight_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : imu_vcam_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : imu_fstcam_gimbal_cam_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : imu_fcam_gimbal_cam_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : aerodimensional_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : actuator_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : smart_battery_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : magnetometer_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : gps_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : barometer_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : battery_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : right_stereo_segmentation_cam_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : depth_cam_rgb_log_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : disp_camera_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : fstcam_opticalflow_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : dist_camera_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : depth_camera_anafi_ai: closed
01-02 03:00:42.701 I shd         (telemetryd-21275)               : right_stereo_cam_lowres_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : right_stereo_cam_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : left_stereo_cam_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : front_segmentation_cam_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : fcam_detection_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : fcam_tracking_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : front_raw_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : front_thumb_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : front_photo_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : front_streaming_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : vertical_camera_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : omniscient_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : gimbal_joint_3_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : gimbal_joint_2_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : gimbal_joint_1_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : gimbal_joint_0_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : calibration_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : hand_launch_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : time_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : isl29501_vertical_anafi_ai: closed
01-02 03:00:42.702 I shd         (telemetryd-21275)               : dist_vcam_anafi_ai: closed
01-02 03:00:42.874 W firmwared_instances(monitor-1db8e19-22138)   : hostname has been changed during the life of the instance, this is bad as it can break some functionalities like ulog's pseudo name-spacing
01-02 03:00:42.874 I firmwared_instances(monitor-1db8e19-22138)   : instance risible_daphne terminated with status 9
01-02 03:00:43.095 I apparmor_config(firmwared-13387)             : apparmor_remove_profile(1db8e193049e7ddadfc35a606c257643e7991cbb)
01-02 03:00:43.099 E firmwared_instances(firmwared-13387)         : rmdir '/var/run/firmwared/1db8e193049e7ddadfc35a606c257643e7991cbb/udev' error 2
01-02 03:00:43.099 E firmwared_instances(firmwared-13387)         : rmdir '/var/run/firmwared/1db8e193049e7ddadfc35a606c257643e7991cbb' error 2
01-02 03:00:43.331 I shd         (gzserver-21331/gz_world_update-21396): time_anafi_ai: closed
01-02 03:00:43.331 I shd         (gzserver-21331/gz_world_update-21396): hand_launch_anafi_ai: closed
01-02 03:00:43.333 I shd         (gzserver-21331/gz_world_update-21396): calibration_anafi_ai: closed
01-02 03:00:43.334 I shd         (gzserver-21331/gz_world_update-21396): gimbal_joint_0_anafi_ai: closed
01-02 03:00:43.334 I shd         (gzserver-21331/gz_world_update-21396): gimbal_joint_1_anafi_ai: closed
01-02 03:00:43.334 I shd         (gzserver-21331/gz_world_update-21396): gimbal_joint_2_anafi_ai: closed
01-02 03:00:43.334 I shd         (gzserver-21331/gz_world_update-21396): gimbal_joint_3_anafi_ai: closed
01-02 03:00:43.334 I shd         (gzserver-21331/gz_world_update-21396): omniscient_anafi_ai: closed
01-02 03:00:43.337 I shd         (gzserver-21331/gz_world_update-21396): vertical_camera_anafi_ai: closed
01-02 03:00:43.338 I shd         (gzserver-21331/gz_world_update-21396): front_streaming_anafi_ai: closed
01-02 03:00:43.339 I shd         (gzserver-21331/gz_world_update-21396): front_photo_anafi_ai: closed
01-02 03:00:43.341 I shd         (gzserver-21331/gz_world_update-21396): front_thumb_anafi_ai: closed
01-02 03:00:43.343 I shd         (gzserver-21331/gz_world_update-21396): front_raw_anafi_ai: closed
01-02 03:00:43.344 I shd         (gzserver-21331/gz_world_update-21396): fcam_tracking_anafi_ai: closed
01-02 03:00:43.346 I shd         (gzserver-21331/gz_world_update-21396): fcam_detection_anafi_ai: closed
01-02 03:00:43.347 I shd         (gzserver-21331/gz_world_update-21396): front_segmentation_cam_anafi_ai: closed
01-02 03:00:43.349 I shd         (gzserver-21331/gz_world_update-21396): left_stereo_cam_anafi_ai: closed
01-02 03:00:43.350 I shd         (gzserver-21331/gz_world_update-21396): right_stereo_cam_anafi_ai: closed
01-02 03:00:43.352 I shd         (gzserver-21331/gz_world_update-21396): right_stereo_cam_lowres_anafi_ai: closed
01-02 03:00:43.354 I shd         (gzserver-21331/gz_world_update-21396): depth_camera_anafi_ai: closed
01-02 03:00:43.355 I shd         (gzserver-21331/gz_world_update-21396): dist_camera_anafi_ai: closed
01-02 03:00:43.355 I shd         (gzserver-21331/gz_world_update-21396): fstcam_opticalflow_anafi_ai: closed
01-02 03:00:43.356 I shd         (gzserver-21331/gz_world_update-21396): disp_camera_anafi_ai: closed
01-02 03:00:43.357 I shd         (gzserver-21331/gz_world_update-21396): depth_cam_rgb_log_anafi_ai: closed
01-02 03:00:43.359 I shd         (gzserver-21331/gz_world_update-21396): right_stereo_segmentation_cam_anafi_ai: closed
01-02 03:00:43.359 I shd         (gzserver-21331/gz_world_update-21396): battery_anafi_ai: closed
01-02 03:00:43.359 I shd         (gzserver-21331/gz_world_update-21396): barometer_anafi_ai: closed
01-02 03:00:43.360 I shd         (gzserver-21331/gz_world_update-21396): gps_anafi_ai: closed
01-02 03:00:43.361 I shd         (gzserver-21331/gz_world_update-21396): magnetometer_anafi_ai: closed
01-02 03:00:43.361 I shd         (gzserver-21331/gz_world_update-21396): smart_battery_anafi_ai: closed
01-02 03:00:43.361 I shd         (gzserver-21331/gz_world_update-21396): actuator_anafi_ai: closed
01-02 03:00:43.366 I shd         (gzserver-21331/gz_world_update-21396): aerodimensional_anafi_ai: closed
01-02 03:00:43.366 I shd         (gzserver-21331/gz_world_update-21396): imu_fcam_gimbal_cam_anafi_ai: closed
01-02 03:00:43.367 I shd         (gzserver-21331/gz_world_update-21396): imu_fstcam_gimbal_cam_anafi_ai: closed
01-02 03:00:43.367 I shd         (gzserver-21331/gz_world_update-21396): imu_vcam_anafi_ai: closed
01-02 03:00:43.368 I shd         (gzserver-21331/gz_world_update-21396): imu_flight_anafi_ai: closed
01-02 03:00:43.369 I shd         (gzserver-21331/gz_world_update-21396): userscript_anafi_ai: closed
01-02 03:00:43.543 I shd         (gzserver-21331)                 : dist_vcam_anafi_ai: closed
01-02 03:00:43.646 I shd         (gzserver-21331)                 : isl29501_vertical_anafi_ai: closed
01-02 03:00:45.365 I             (gzserver-21331/gz_world_update-22048): [2023-01-30 21:32:23] [info] Error getting remote endpoint: system:9 (Bad file descriptor)
01-02 03:00:45.365 I             (gzserver-21331/gz_world_update-22048): [2023-01-30 21:32:23] [info] asio async_shutdown error: system:9 (Bad file descriptor)
01-02 03:00:45.365 I             (gzserver-21331/gz_world_update-22048): [2023-01-30 21:32:23] [info] handle_accept error: Operation canceled
01-02 03:00:45.365 I             (gzserver-21331/gz_world_update-22048): [2023-01-30 21:32:23] [info] Stopping acceptance of new connections because the underlying transport is no longer listening.

Any clue of what could the problem?
Any way I can provide more info?

thanks

ocrave · January 31, 2023, 8:13am

I forgot to tell you that fdc drop_all firmwares should only be done when Sphinx is not running, and can just be done once, just to make sure that there is no corrupted firmwares on your disk.

Your issue this time seems to be that the port number 11345 used by Sphinx is not available, probably because of a crash (this explains why parrot-ue4-empty fails to send requests to the server). When that happens, firmwared must be restarted to liberate the port:

sudo systemctl restart firmwared.service

pixelblade · January 31, 2023, 8:17pm

Thanks for the patience…

The sudo systemctl restart firmwared.service plus he option ::wifi_iface= made the trick… Now I can see the drone stable in the environment… Now starts the interesting part Many thanks again…

I have a last question, what kind of limitations does the option ::wifi_iface= has?
At the moment my plan is to use sphinx for code development and testing. Therefore I don’t need to interact with a real drone.

Regards

ocrave · February 1, 2023, 7:53am

The wifi_iface option tells the firmware which wifi interface to use as a wireless access point. It is mainly used to connect with a FreeFlight app running on a phone. With the default auto value, it automatically takes the first wireless interface it founds which may already be used by your system. You can set it to an empty string to deactivate this option.

pixelblade · February 26, 2023, 11:54am

Sorry for the late reply…
Thanks for the explanation, everything is working as intended now…

Cheers

system · February 28, 2023, 3:44pm

This topic was automatically closed after 30 days. New replies are no longer allowed.

Sphinx - Received an error from firmwared:This operation is not permitted from the current state

System: Ubuntu 20.04 CPU: 2X Xeon E5-2620 3v GPU: 1660 Super RAM: 16gb

System:
Ubuntu 20.04
CPU: 2X Xeon E5-2620 3v
GPU: 1660 Super
RAM: 16gb