2024-08-29T19:09:19.676Z,1724958559.676 [Supervisor](DEBUG): Initializing supervisor.
2024-08-29T19:09:19.680Z,1724958559.680 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-08-29T19:09:19.681Z,1724958559.681 [SyncHandler](INFO): Protected caller Thread ID is 3469
2024-08-29T19:09:19.681Z,1724958559.681 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-08-29T19:09:19.682Z,1724958559.682 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-08-29T19:09:19.682Z,1724958559.682 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3470
2024-08-29T19:09:19.686Z,1724958559.686 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-08-29T19:09:19.704Z,1724958559.704 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-08-29T19:09:19.705Z,1724958559.705 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-08-29T19:09:19.706Z,1724958559.706 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3471
2024-08-29T19:09:19.710Z,1724958559.710 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-08-29T19:09:19.711Z,1724958559.711 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-08-29T19:09:19.711Z,1724958559.711 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3472
2024-08-29T19:09:19.713Z,1724958559.713 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-08-29T19:09:19.714Z,1724958559.714 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-08-29T19:09:19.715Z,1724958559.715 [logger ThreadHandler](INFO): Protected caller Thread ID is 3473
2024-08-29T19:09:19.718Z,1724958559.718 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-08-29T19:09:19.719Z,1724958559.719 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-08-29T19:09:19.720Z,1724958559.720 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-08-29T19:09:19.895Z,1724958559.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-08-29T19:09:19.896Z,1724958559.896 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-08-29T19:09:20.156Z,1724958560.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-08-29T19:09:20.156Z,1724958560.156 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-08-29T19:09:20.449Z,1724958560.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-08-29T19:09:20.450Z,1724958560.450 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-08-29T19:09:20.575Z,1724958560.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-08-29T19:09:20.575Z,1724958560.575 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-08-29T19:09:21.400Z,1724958561.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-08-29T19:09:21.401Z,1724958561.401 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-08-29T19:09:22.377Z,1724958562.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-08-29T19:09:22.377Z,1724958562.377 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-08-29T19:09:23.509Z,1724958563.509 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-08-29T19:09:23.509Z,1724958563.509 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-08-29T19:09:23.987Z,1724958563.987 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-08-29T19:09:23.987Z,1724958563.987 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-08-29T19:09:24.760Z,1724958564.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-08-29T19:09:24.760Z,1724958564.760 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-08-29T19:09:25.178Z,1724958565.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-08-29T19:09:25.179Z,1724958565.179 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-08-29T19:09:25.434Z,1724958565.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-08-29T19:09:25.435Z,1724958565.435 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-08-29T19:09:25.593Z,1724958565.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-08-29T19:09:25.593Z,1724958565.593 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-08-29T19:09:25.690Z,1724958565.690 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-08-29T19:09:25.784Z,1724958565.784 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-08-29T19:09:25.996Z,1724958565.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-08-29T19:09:25.997Z,1724958565.997 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-08-29T19:09:26.254Z,1724958566.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-08-29T19:09:26.256Z,1724958566.256 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2024-08-29T19:09:26.257Z,1724958566.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2024-08-29T19:09:26.356Z,1724958566.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2024-08-29T19:09:26.635Z,1724958566.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-08-29T19:09:26.636Z,1724958566.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2024-08-29T19:09:26.749Z,1724958566.749 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2024-08-29T19:09:26.937Z,1724958566.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2024-08-29T19:09:27.052Z,1724958567.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2024-08-29T19:09:27.181Z,1724958567.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2024-08-29T19:09:27.311Z,1724958567.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2024-08-29T19:09:27.435Z,1724958567.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2024-08-29T19:09:27.619Z,1724958567.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2024-08-29T19:09:27.733Z,1724958567.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2024-08-29T19:09:27.832Z,1724958567.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2024-08-29T19:09:27.947Z,1724958567.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2024-08-29T19:09:28.055Z,1724958568.055 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2024-08-29T19:09:28.055Z,1724958568.055 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-08-29T19:09:28.062Z,1724958568.062 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-08-29T19:09:28.228Z,1724958568.228 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-08-29T19:09:28.228Z,1724958568.228 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-08-29T19:09:28.385Z,1724958568.385 [BuoyancyServo] Loaded
2024-08-29T19:09:28.385Z,1724958568.385 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-08-29T19:09:28.408Z,1724958568.408 [ElevatorServo] Loaded
2024-08-29T19:09:28.409Z,1724958568.409 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-08-29T19:09:28.431Z,1724958568.431 [MassServo] Loaded
2024-08-29T19:09:28.431Z,1724958568.431 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-08-29T19:09:28.453Z,1724958568.453 [RudderServo] Loaded
2024-08-29T19:09:28.453Z,1724958568.453 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-08-29T19:09:28.470Z,1724958568.470 [ThrusterHE] Loaded
2024-08-29T19:09:28.470Z,1724958568.470 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-08-29T19:09:28.471Z,1724958568.471 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-08-29T19:09:28.471Z,1724958568.471 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-08-29T19:09:28.605Z,1724958568.605 [SBIT](DEBUG): Construct Startup Built In Test.
2024-08-29T19:09:28.620Z,1724958568.620 [SBIT] Loaded
2024-08-29T19:09:28.620Z,1724958568.620 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-08-29T19:09:28.623Z,1724958568.623 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-08-29T19:09:28.643Z,1724958568.643 [IBIT] Loaded
2024-08-29T19:09:28.643Z,1724958568.643 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-08-29T19:09:28.649Z,1724958568.649 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-08-29T19:09:28.869Z,1724958568.869 [CBIT] Loaded
2024-08-29T19:09:28.869Z,1724958568.869 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-08-29T19:09:28.870Z,1724958568.870 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-08-29T19:09:28.870Z,1724958568.870 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-08-29T19:09:29.036Z,1724958569.036 [CTD_Seabird] Loaded
2024-08-29T19:09:29.036Z,1724958569.036 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-08-29T19:09:29.038Z,1724958569.038 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2024-08-29T19:09:29.038Z,1724958569.038 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3561
2024-08-29T19:09:29.073Z,1724958569.073 [ESPComponent] Loaded
2024-08-29T19:09:29.073Z,1724958569.073 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-08-29T19:09:29.094Z,1724958569.094 [PAR_Licor] Loaded
2024-08-29T19:09:29.094Z,1724958569.094 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-08-29T19:09:29.137Z,1724958569.137 [WetLabsBB2FL] Loaded
2024-08-29T19:09:29.137Z,1724958569.137 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-08-29T19:09:29.138Z,1724958569.138 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2024-08-29T19:09:29.139Z,1724958569.139 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3562
2024-08-29T19:09:29.140Z,1724958569.140 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-08-29T19:09:29.140Z,1724958569.140 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-08-29T19:09:29.299Z,1724958569.299 [DepthRateCalculator] Loaded
2024-08-29T19:09:29.299Z,1724958569.299 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-08-29T19:09:29.305Z,1724958569.305 [PitchRateCalculator] Loaded
2024-08-29T19:09:29.305Z,1724958569.305 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-08-29T19:09:29.315Z,1724958569.315 [SpeedCalculator] Loaded
2024-08-29T19:09:29.315Z,1724958569.315 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-08-29T19:09:29.319Z,1724958569.319 [YawRateCalculator] Loaded
2024-08-29T19:09:29.320Z,1724958569.320 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-08-29T19:09:29.418Z,1724958569.418 [ElevatorOffsetCalculator] Loaded
2024-08-29T19:09:29.420Z,1724958569.420 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-08-29T19:09:29.420Z,1724958569.420 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-08-29T19:09:29.444Z,1724958569.444 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-08-29T19:09:29.755Z,1724958569.755 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-08-29T19:09:29.755Z,1724958569.755 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-08-29T19:09:29.781Z,1724958569.781 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-08-29T19:09:29.782Z,1724958569.782 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-08-29T19:09:29.995Z,1724958569.995 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-08-29T19:09:29.995Z,1724958569.995 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-08-29T19:09:30.009Z,1724958570.009 [NavChart] Loaded
2024-08-29T19:09:30.009Z,1724958570.009 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-08-29T19:09:30.015Z,1724958570.015 [UniversalFixResidualReporter] Loaded
2024-08-29T19:09:30.015Z,1724958570.015 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-08-29T19:09:30.016Z,1724958570.016 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-08-29T19:09:30.016Z,1724958570.016 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-08-29T19:09:30.462Z,1724958570.462 [AHRS_M2] Loaded
2024-08-29T19:09:30.463Z,1724958570.463 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-08-29T19:09:30.501Z,1724958570.501 [BackseatComponent] Loaded
2024-08-29T19:09:30.502Z,1724958570.502 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-08-29T19:09:30.503Z,1724958570.503 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A694E0
2024-08-29T19:09:30.503Z,1724958570.503 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3563
2024-08-29T19:09:30.506Z,1724958570.506 [LcmUniversalReporter] Loaded
2024-08-29T19:09:30.506Z,1724958570.506 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-08-29T19:09:31.246Z,1724958571.246 [BPC1] Loaded
2024-08-29T19:09:31.246Z,1724958571.246 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-08-29T19:09:31.336Z,1724958571.336 [DATMMP] Loaded
2024-08-29T19:09:31.336Z,1724958571.336 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-08-29T19:09:31.337Z,1724958571.337 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0
2024-08-29T19:09:31.338Z,1724958571.338 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 3564
2024-08-29T19:09:31.410Z,1724958571.410 [DataOverHttps] Loaded
2024-08-29T19:09:31.410Z,1724958571.410 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-08-29T19:09:31.411Z,1724958571.411 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0
2024-08-29T19:09:31.411Z,1724958571.411 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3565
2024-08-29T19:09:31.435Z,1724958571.435 [Depth_Keller] Loaded
2024-08-29T19:09:31.435Z,1724958571.435 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-08-29T19:09:31.440Z,1724958571.440 [DropWeight] Loaded
2024-08-29T19:09:31.440Z,1724958571.440 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-08-29T19:09:31.481Z,1724958571.481 [DVL_micro] Loaded
2024-08-29T19:09:31.482Z,1724958571.482 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-08-29T19:09:31.544Z,1724958571.544 [NAL9602] Loaded
2024-08-29T19:09:31.544Z,1724958571.544 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-08-29T19:09:31.570Z,1724958571.570 [Onboard] Loaded
2024-08-29T19:09:31.570Z,1724958571.570 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-08-29T19:09:31.571Z,1724958571.571 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0
2024-08-29T19:09:31.571Z,1724958571.571 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3566
2024-08-29T19:09:31.585Z,1724958571.585 [Power24vConverter] Loaded
2024-08-29T19:09:31.586Z,1724958571.586 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-08-29T19:09:31.601Z,1724958571.601 [Radio_Surface] Loaded
2024-08-29T19:09:31.601Z,1724958571.601 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-08-29T19:09:31.602Z,1724958571.602 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0
2024-08-29T19:09:31.603Z,1724958571.603 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3567
2024-08-29T19:09:31.618Z,1724958571.618 [Sonardyne_Nano] Loaded
2024-08-29T19:09:31.619Z,1724958571.619 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-08-29T19:09:31.619Z,1724958571.619 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-08-29T19:09:31.620Z,1724958571.620 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-08-29T19:09:31.686Z,1724958571.686 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-08-29T19:09:31.686Z,1724958571.686 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-08-29T19:09:31.737Z,1724958571.737 [VerticalControl](DEBUG): Construct VerticalControl.
2024-08-29T19:09:31.787Z,1724958571.787 [VerticalControl] Loaded
2024-08-29T19:09:31.787Z,1724958571.787 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-08-29T19:09:31.790Z,1724958571.790 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-08-29T19:09:31.833Z,1724958571.833 [HorizontalControl] Loaded
2024-08-29T19:09:31.834Z,1724958571.834 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-08-29T19:09:31.836Z,1724958571.836 [SpeedControl](DEBUG): Construct SpeedControl.
2024-08-29T19:09:31.838Z,1724958571.838 [SpeedControl] Loaded
2024-08-29T19:09:31.838Z,1724958571.838 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-08-29T19:09:31.841Z,1724958571.841 [LoopControl](DEBUG): Construct LoopControl.
2024-08-29T19:09:31.841Z,1724958571.841 [LoopControl] Loaded
2024-08-29T19:09:31.842Z,1724958571.842 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-08-29T19:09:31.842Z,1724958571.842 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-08-29T19:09:31.843Z,1724958571.843 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-08-29T19:09:31.853Z,1724958571.853 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-08-29T19:09:31.853Z,1724958571.853 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-08-29T19:09:32.173Z,1724958572.173 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-08-29T19:09:32.179Z,1724958572.179 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-08-29T19:09:32.182Z,1724958572.182 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-08-29T19:09:32.193Z,1724958572.193 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-08-29T19:09:32.194Z,1724958572.194 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-08-29T19:09:32.195Z,1724958572.195 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3568
2024-08-29T19:09:32.199Z,1724958572.199 [Supervisor](INFO): Main Thread ID is 1087
2024-08-29T19:09:32.199Z,1724958572.199 [Supervisor](DEBUG): Running supervisor.
2024-08-29T19:09:32.200Z,1724958572.200 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3569
2024-08-29T19:09:32.200Z,1724958572.200 [CommandExec](INFO): Initializing the command executive.
2024-08-29T19:09:32.202Z,1724958572.202 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3570
2024-08-29T19:09:32.205Z,1724958572.205 [controlThread ThreadHandler](INFO): Handler Thread ID is 3571
2024-08-29T19:09:32.205Z,1724958572.205 [controlThread](DEBUG): Initializing ControlThread
2024-08-29T19:09:32.207Z,1724958572.207 [SBIT](INFO): Initialize SBIT Component.
2024-08-29T19:09:32.207Z,1724958572.207 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60
2024-08-29T19:09:32.208Z,1724958572.208 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95
2024-08-29T19:09:32.208Z,1724958572.208 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-08-29T19:09:32.209Z,1724958572.209 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-08-29T19:09:32.210Z,1724958572.210 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2024-08-29T19:09:32.211Z,1724958572.211 [IBIT](INFO): Initialize IBIT Component.
2024-08-29T19:09:32.212Z,1724958572.212 [CBIT](DEBUG): Initialize CBIT Component.
2024-08-29T19:09:32.213Z,1724958572.213 [logger ThreadHandler](INFO): Handler Thread ID is 3572
2024-08-29T19:09:32.224Z,1724958572.224 [CBIT](DEBUG): Initialized mux pins.
2024-08-29T19:09:32.224Z,1724958572.224 [CBIT](DEBUG): Initializing the watchdog timer.
2024-08-29T19:09:32.232Z,1724958572.232 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3573
2024-08-29T19:09:32.233Z,1724958572.233 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-08-29T19:09:32.237Z,1724958572.237 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3575
2024-08-29T19:09:32.240Z,1724958572.240 [WetLabsBB2FL](INFO): Powering up
2024-08-29T19:09:32.241Z,1724958572.241 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3577
2024-08-29T19:09:32.248Z,1724958572.248 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-08-29T19:09:32.248Z,1724958572.248 [CBIT](DEBUG): Initializing heartbeat.
2024-08-29T19:09:32.249Z,1724958572.249 [DATMMP ThreadHandler](INFO): Handler Thread ID is 3578
2024-08-29T19:09:32.250Z,1724958572.250 [DATMMP](INFO): Start
2024-08-29T19:09:32.250Z,1724958572.250 [DATMMP](INFO): Powering up
2024-08-29T19:09:32.250Z,1724958572.250 [DATMMP](DEBUG): Initializing DATMMP.
2024-08-29T19:09:32.253Z,1724958572.253 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3580
2024-08-29T19:09:32.255Z,1724958572.255 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-08-29T19:09:32.265Z,1724958572.265 [Onboard ThreadHandler](INFO): Handler Thread ID is 3581
2024-08-29T19:09:32.282Z,1724958572.282 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3582
2024-08-29T19:09:32.300Z,1724958572.300 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3583
2024-08-29T19:09:32.302Z,1724958572.302 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-08-29T19:09:32.302Z,1724958572.302 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-08-29T19:09:32.303Z,1724958572.303 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-08-29T19:09:32.303Z,1724958572.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-08-29T19:09:32.303Z,1724958572.303 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-08-29T19:09:32.303Z,1724958572.303 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-08-29T19:09:32.320Z,1724958572.320 [CBIT](DEBUG): Deactivating GF circuits.
2024-08-29T19:09:32.320Z,1724958572.320 [CBIT](DEBUG): Deactivating emergency mode.
2024-08-29T19:09:32.360Z,1724958572.360 [CBIT](DEBUG): Backplane powered.
2024-08-29T19:09:32.364Z,1724958572.364 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-08-29T19:09:32.365Z,1724958572.365 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-08-29T19:09:32.365Z,1724958572.365 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-08-29T19:09:32.365Z,1724958572.365 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-08-29T19:09:32.366Z,1724958572.366 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-08-29T19:09:32.367Z,1724958572.367 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-08-29T19:09:32.367Z,1724958572.367 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-08-29T19:09:32.382Z,1724958572.382 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-08-29T19:09:32.384Z,1724958572.384 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-08-29T19:09:32.385Z,1724958572.385 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-08-29T19:09:32.385Z,1724958572.385 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-08-29T19:09:32.386Z,1724958572.386 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-08-29T19:09:32.387Z,1724958572.387 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-08-29T19:09:32.387Z,1724958572.387 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-08-29T19:09:32.396Z,1724958572.396 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-08-29T19:09:32.461Z,1724958572.461 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-08-29T19:09:32.482Z,1724958572.482 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-29T19:09:32.504Z,1724958572.504 [DATMMP](INFO): Starting
2024-08-29T19:09:32.544Z,1724958572.544 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-08-29T19:09:32.545Z,1724958572.545 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-08-29T19:09:32.545Z,1724958572.545 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-08-29T19:09:32.546Z,1724958572.546 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-08-29T19:09:32.688Z,1724958572.688 [Radio_Surface](INFO): Powering up
2024-08-29T19:09:32.756Z,1724958572.756 [DATMMP](INFO): Starting
2024-08-29T19:09:32.861Z,1724958572.861 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-08-29T19:09:32.867Z,1724958572.867 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-08-29T19:09:32.888Z,1724958572.888 [Default:A.Wait](DEBUG): Construct Wait.
2024-08-29T19:09:32.890Z,1724958572.890 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-29T19:09:32.918Z,1724958572.918 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-08-29T19:09:32.936Z,1724958572.936 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-08-29T19:09:32.942Z,1724958572.942 [Default:E.Execute](DEBUG): Construct Execute.
2024-08-29T19:09:32.956Z,1724958572.956 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-08-29T19:09:32.976Z,1724958572.976 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-29T19:09:32.990Z,1724958572.990 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-29T19:09:33.008Z,1724958573.008 [DATMMP](INFO): Starting
2024-08-29T19:09:33.058Z,1724958573.058 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2024-08-29T19:09:33.061Z,1724958573.061 [Power24vConverter](INFO): Powering up.
2024-08-29T19:09:33.061Z,1724958573.061 [Sonardyne_Nano](INFO): Initializing.
2024-08-29T19:09:33.100Z,1724958573.100 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-29T19:09:33.122Z,1724958573.122 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-08-29T19:09:33.128Z,1724958573.128 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-08-29T19:09:33.129Z,1724958573.129 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-29T19:09:33.137Z,1724958573.137 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-29T19:09:33.137Z,1724958573.137 [MassServo](DEBUG): Initializing EZServoServo.
2024-08-29T19:09:33.144Z,1724958573.144 [MassServo](DEBUG): Initializing MassServo.
2024-08-29T19:09:33.145Z,1724958573.145 [RudderServo](DEBUG): Initializing EZServoServo.
2024-08-29T19:09:33.152Z,1724958573.152 [RudderServo](DEBUG): Initializing RudderServo.
2024-08-29T19:09:33.153Z,1724958573.153 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-08-29T19:09:33.160Z,1724958573.160 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-08-29T19:09:33.260Z,1724958573.260 [DATMMP](INFO): Starting
2024-08-29T19:09:33.512Z,1724958573.512 [DATMMP](INFO): Starting
2024-08-29T19:09:33.764Z,1724958573.764 [DATMMP](INFO): Starting
2024-08-29T19:09:34.016Z,1724958574.016 [DATMMP](INFO): Starting
2024-08-29T19:09:34.269Z,1724958574.269 [DATMMP](INFO): Starting
2024-08-29T19:09:34.349Z,1724958574.349 [WetLabsBB2FL](INFO): Powering down
2024-08-29T19:09:34.520Z,1724958574.520 [DATMMP](INFO): Starting
2024-08-29T19:09:34.772Z,1724958574.772 [DATMMP](INFO): Starting
2024-08-29T19:09:35.010Z,1724958575.010 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-08-29T19:09:35.024Z,1724958575.024 [DATMMP](INFO): Starting
2024-08-29T19:09:35.276Z,1724958575.276 [DATMMP](INFO): Starting
2024-08-29T19:09:35.528Z,1724958575.528 [DATMMP](INFO): Starting
2024-08-29T19:09:35.780Z,1724958575.780 [DATMMP](INFO): Starting
2024-08-29T19:09:36.032Z,1724958576.032 [DATMMP](INFO): Starting
2024-08-29T19:09:36.284Z,1724958576.284 [DATMMP](INFO): Starting
2024-08-29T19:09:36.297Z,1724958576.297 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2024-08-29T19:09:36.536Z,1724958576.536 [DATMMP](INFO): Starting
2024-08-29T19:09:36.788Z,1724958576.788 [DATMMP](INFO): Starting
2024-08-29T19:09:37.041Z,1724958577.041 [DATMMP](INFO): Starting
2024-08-29T19:09:37.292Z,1724958577.292 [DATMMP](INFO): Starting
2024-08-29T19:09:37.545Z,1724958577.545 [DATMMP](INFO): Starting
2024-08-29T19:09:37.796Z,1724958577.796 [DATMMP](INFO): Starting
2024-08-29T19:09:38.048Z,1724958578.048 [DATMMP](INFO): Starting
2024-08-29T19:09:38.300Z,1724958578.300 [DATMMP](INFO): Starting
2024-08-29T19:09:38.552Z,1724958578.552 [DATMMP](INFO): Starting
2024-08-29T19:09:38.804Z,1724958578.804 [DATMMP](INFO): Starting
2024-08-29T19:09:39.057Z,1724958579.057 [DATMMP](INFO): Starting
2024-08-29T19:09:39.308Z,1724958579.308 [DATMMP](INFO): Starting
2024-08-29T19:09:39.560Z,1724958579.560 [DATMMP](INFO): Starting
2024-08-29T19:09:39.812Z,1724958579.812 [DATMMP](INFO): Starting
2024-08-29T19:09:40.064Z,1724958580.064 [DATMMP](INFO): Starting
2024-08-29T19:09:40.316Z,1724958580.316 [DATMMP](INFO): Starting
2024-08-29T19:09:40.568Z,1724958580.568 [DATMMP](INFO): Starting
2024-08-29T19:09:40.820Z,1724958580.820 [DATMMP](INFO): Starting
2024-08-29T19:09:41.072Z,1724958581.072 [DATMMP](INFO): Starting
2024-08-29T19:09:41.324Z,1724958581.324 [DATMMP](INFO): Starting
2024-08-29T19:09:41.576Z,1724958581.576 [DATMMP](INFO): Starting
2024-08-29T19:09:41.828Z,1724958581.828 [DATMMP](INFO): Starting
2024-08-29T19:09:42.080Z,1724958582.080 [DATMMP](INFO): Starting
2024-08-29T19:09:42.332Z,1724958582.332 [DATMMP](INFO): Starting
2024-08-29T19:09:42.584Z,1724958582.584 [DATMMP](INFO): Starting
2024-08-29T19:09:42.836Z,1724958582.836 [DATMMP](INFO): Starting
2024-08-29T19:09:43.088Z,1724958583.088 [DATMMP](INFO): Starting
2024-08-29T19:09:43.340Z,1724958583.340 [DATMMP](INFO): Starting
2024-08-29T19:09:43.592Z,1724958583.592 [DATMMP](INFO): Starting
2024-08-29T19:09:43.844Z,1724958583.844 [DATMMP](INFO): Starting
2024-08-29T19:09:44.096Z,1724958584.096 [DATMMP](INFO): Starting
2024-08-29T19:09:44.349Z,1724958584.349 [DATMMP](INFO): Starting
2024-08-29T19:09:44.600Z,1724958584.600 [DATMMP](INFO): Starting
2024-08-29T19:09:44.852Z,1724958584.852 [DATMMP](INFO): Starting
2024-08-29T19:09:45.104Z,1724958585.104 [DATMMP](INFO): Starting
2024-08-29T19:09:45.104Z,1724958585.104 [DATMMP](INFO): DAT read:
2024-08-29T19:09:45.105Z,1724958585.105 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-08-29T19:09:45.356Z,1724958585.356 [DATMMP](INFO): Starting
2024-08-29T19:09:45.608Z,1724958585.608 [DATMMP](INFO): Starting
2024-08-29T19:09:45.860Z,1724958585.860 [DATMMP](INFO): Starting
2024-08-29T19:09:46.112Z,1724958586.112 [DATMMP](INFO): Starting
2024-08-29T19:09:46.364Z,1724958586.364 [DATMMP](INFO): Starting
2024-08-29T19:09:46.616Z,1724958586.616 [DATMMP](INFO): Starting
2024-08-29T19:09:46.868Z,1724958586.868 [DATMMP](INFO): Starting
2024-08-29T19:09:46.869Z,1724958586.869 [DATMMP](INFO): DAT read: MF Frequency Band
2024-08-29T19:09:46.870Z,1724958586.870 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-08-29T19:09:46.871Z,1724958586.871 [DATMMP](INFO): DAT read: Jul 29 2000 19:09:41
2024-08-29T19:09:47.120Z,1724958587.120 [DATMMP](INFO): Starting
2024-08-29T19:09:47.372Z,1724958587.372 [DATMMP](INFO): Starting
2024-08-29T19:09:47.624Z,1724958587.624 [DATMMP](INFO): Starting
2024-08-29T19:09:47.876Z,1724958587.876 [DATMMP](INFO): Starting
2024-08-29T19:09:47.877Z,1724958587.877 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-08-29T19:09:47.878Z,1724958587.878 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-08-29T19:09:47.878Z,1724958587.878 [DATMMP](INFO): commRate: 1200
2024-08-29T19:09:48.128Z,1724958588.128 [DATMMP](INFO): Starting
2024-08-29T19:09:48.380Z,1724958588.380 [DATMMP](INFO): Starting
2024-08-29T19:09:48.632Z,1724958588.632 [DATMMP](INFO): Starting
2024-08-29T19:09:48.884Z,1724958588.884 [DATMMP](INFO): Starting
2024-08-29T19:09:49.136Z,1724958589.136 [DATMMP](INFO): Starting
2024-08-29T19:09:49.388Z,1724958589.388 [DATMMP](INFO): Starting
2024-08-29T19:09:49.640Z,1724958589.640 [DATMMP](INFO): Starting
2024-08-29T19:09:49.892Z,1724958589.892 [DATMMP](INFO): Starting
2024-08-29T19:09:49.944Z,1724958589.944 [DATMMP](INFO): entering command mode
2024-08-29T19:09:50.144Z,1724958590.144 [DATMMP](INFO): Starting
2024-08-29T19:09:50.144Z,1724958590.144 [DATMMP](INFO): DAT read:
2024-08-29T19:09:50.145Z,1724958590.145 [DATMMP](INFO): DAT read: user:1>
2024-08-29T19:09:50.145Z,1724958590.145 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-08-29T19:09:50.145Z,1724958590.145 [DATMMP](INFO): entering MMP mode
2024-08-29T19:09:50.396Z,1724958590.396 [DATMMP](INFO): Starting
2024-08-29T19:09:50.397Z,1724958590.397 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-29T19:09:50.399Z,1724958590.399 [DATMMP](INFO): status rx: x1
2024-08-29T19:09:50.400Z,1724958590.400 [DATMMP](INFO): MMP status: 1: started
2024-08-29T19:09:50.400Z,1724958590.400 [DATMMP](INFO): Received message type: status
2024-08-29T19:09:50.400Z,1724958590.400 [DATMMP](INFO): Handled
2024-08-29T19:09:50.648Z,1724958590.648 [DATMMP](INFO): Starting
2024-08-29T19:09:50.649Z,1724958590.649 [DATMMP](INFO): Sent Tx Power Config 8
2024-08-29T19:09:50.649Z,1724958590.649 [DATMMP](INFO): Setting local address: 5
2024-08-29T19:09:50.651Z,1724958590.651 [DATMMP](INFO): Setting time to: 19:9:50 And date to:8/29/2024
2024-08-29T19:09:50.900Z,1724958590.900 [DATMMP](INFO): Starting
2024-08-29T19:09:50.900Z,1724958590.900 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-29T19:09:50.901Z,1724958590.901 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-08-29T19:09:50.901Z,1724958590.901 [DATMMP](INFO): Received message type: mdmpower
2024-08-29T19:09:50.902Z,1724958590.902 [DATMMP](INFO): Handled
2024-08-29T19:09:51.152Z,1724958591.152 [DATMMP](INFO): Starting
2024-08-29T19:09:51.153Z,1724958591.153 [DATMMP](INFO): GSXN notify for xid: 10
2024-08-29T19:09:51.153Z,1724958591.153 [DATMMP](INFO): sreg_txpower rx: x8
2024-08-29T19:09:51.153Z,1724958591.153 [DATMMP](INFO): Received message type: sreg_txpower
2024-08-29T19:09:51.154Z,1724958591.154 [DATMMP](INFO): Match for : sreg_txpower
2024-08-29T19:09:51.154Z,1724958591.154 [DATMMP](INFO): ACK
2024-08-29T19:09:51.404Z,1724958591.404 [DATMMP](INFO): Starting
2024-08-29T19:09:51.404Z,1724958591.404 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-29T19:09:51.405Z,1724958591.405 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0
2024-08-29T19:09:51.405Z,1724958591.405 [DATMMP](INFO): Received message type: mdmlocal
2024-08-29T19:09:51.405Z,1724958591.405 [DATMMP](INFO): Handled
2024-08-29T19:09:51.656Z,1724958591.656 [DATMMP](INFO): Starting
2024-08-29T19:09:51.656Z,1724958591.656 [DATMMP](INFO): GSXN notify for xid: 11
2024-08-29T19:09:51.657Z,1724958591.657 [DATMMP](INFO): sreg_locaddr rx: x5
2024-08-29T19:09:51.657Z,1724958591.657 [DATMMP](INFO): Received message type: sreg_locaddr
2024-08-29T19:09:51.657Z,1724958591.657 [DATMMP](INFO): Match for : sreg_locaddr
2024-08-29T19:09:51.657Z,1724958591.657 [DATMMP](INFO): ACK
2024-08-29T19:09:51.908Z,1724958591.908 [DATMMP](INFO): Starting
2024-08-29T19:09:51.908Z,1724958591.908 [DATMMP](INFO): GSXN notify for xid: 12
2024-08-29T19:09:51.910Z,1724958591.910 [DATMMP](INFO): timedate rx: x0 x13 x9 x32 x7 x1D x7C x0
2024-08-29T19:09:51.910Z,1724958591.910 [DATMMP](INFO): Received message type: timedate
2024-08-29T19:09:51.910Z,1724958591.910 [DATMMP](INFO): Match for : timedate
2024-08-29T19:09:51.910Z,1724958591.910 [DATMMP](INFO): ACK
2024-08-29T19:09:52.160Z,1724958592.160 [DATMMP](INFO): Starting
2024-08-29T19:09:52.160Z,1724958592.160 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-29T19:09:52.161Z,1724958592.161 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-08-29T19:09:52.161Z,1724958592.161 [DATMMP](INFO): Received message type: sys_verb
2024-08-29T19:09:52.161Z,1724958592.161 [DATMMP](INFO): Handled
2024-08-29T19:09:52.412Z,1724958592.412 [DATMMP](INFO): Starting
2024-08-29T19:09:52.412Z,1724958592.412 [DATMMP](INFO): GSXN notify for xid: 13
2024-08-29T19:09:52.413Z,1724958592.413 [DATMMP](INFO): sreg_verbosity rx: x0
2024-08-29T19:09:52.413Z,1724958592.413 [DATMMP](INFO): Received message type: sreg_verbosity
2024-08-29T19:09:52.413Z,1724958592.413 [DATMMP](INFO): Match for : sreg_verbosity
2024-08-29T19:09:52.413Z,1724958592.413 [DATMMP](INFO): ACK
2024-08-29T19:09:52.664Z,1724958592.664 [DATMMP](INFO): Starting
2024-08-29T19:09:52.664Z,1724958592.664 [DATMMP](INFO): GSXN notify for xid: 14
2024-08-29T19:09:52.665Z,1724958592.665 [DATMMP](INFO): version rx: x2 x5
2024-08-29T19:09:52.665Z,1724958592.665 [DATMMP](INFO): DatMMPVersion: 2.5
2024-08-29T19:09:52.665Z,1724958592.665 [DATMMP](INFO): Received message type: version
2024-08-29T19:09:52.666Z,1724958592.666 [DATMMP](INFO): Match for : version
2024-08-29T19:09:52.666Z,1724958592.666 [DATMMP](INFO): ACK
2024-08-29T19:09:52.666Z,1724958592.666 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-08-29T19:10:01.689Z,1724958601.689 [NAL9602](INFO): Powering up NAL9602
2024-08-29T19:10:07.028Z,1724958607.028 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-29T19:10:07.029Z,1724958607.029 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-08-29T19:10:07.029Z,1724958607.029 [DATMMP](INFO): Received message type: timesync
2024-08-29T19:10:07.030Z,1724958607.030 [DATMMP](INFO): Handled
2024-08-29T19:10:12.588Z,1724958612.588 [NAL9602](INFO): NAL9602 initialized
2024-08-29T19:10:16.866Z,1724958616.866 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-29T19:10:16.867Z,1724958616.867 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-08-29T19:10:16.867Z,1724958616.867 [DATMMP](INFO): Received message type: timesync
2024-08-29T19:10:16.867Z,1724958616.867 [DATMMP](INFO): Handled
2024-08-29T19:11:44.150Z,1724958704.150 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004438
2024-08-29T19:12:12.991Z,1724958732.991 [SBIT](IMPORTANT): Beginning Startup BIT
2024-08-29T19:12:12.996Z,1724958732.996 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-29T19:12:22.449Z,1724958742.449 [CommandExec](IMPORTANT): got command report
2024-08-29T19:12:24.384Z,1724958744.384 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.453165
CHAN A1 (24V): 0.663958
CHAN A2 (12V): 0.295369
CHAN A3 (5V): 0.073575
CHAN B0 (3.3V): 0.042605
CHAN B1 (3.15aV): 0.046497
CHAN B2 (3.15bV): 0.048240
CHAN B3 (GND): -0.021570
OPEN: 0.016932
Full Scale: +/- 1 mA
2024-08-29T19:12:26.391Z,1724958746.391 [SBIT](FAULT): Mass: EXPECTED:0.041000 ACTUAL:0.040672
2024-08-29T19:12:27.121Z,1724958747.121 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-08-29T19:12:31.448Z,1724958751.448 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position
2024-08-29T19:12:31.675Z,1724958751.675 [Reporter](INFO): platform_buoyancy_position 950.162476 cc
2024-08-29T19:12:32.377Z,1724958752.377 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-08-29T19:12:32.377Z,1724958752.377 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-29T19:12:32.521Z,1724958752.521 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-29T19:12:32.888Z,1724958752.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-29T19:12:32.888Z,1724958752.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-08-29T19:12:43.917Z,1724958763.917 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 400 cubic_centimeter
2024-08-29T19:12:43.925Z,1724958763.925 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2024-08-29T19:12:44.246Z,1724958764.246 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-29T19:12:49.919Z,1724958769.919 [Reporter](INFO): platform_buoyancy_position 950.028198 cc
2024-08-29T19:12:50.322Z,1724958770.322 [Reporter](INFO): platform_buoyancy_position 948.013672 cc
2024-08-29T19:12:50.735Z,1724958770.735 [Reporter](INFO): platform_buoyancy_position 945.730591 cc
2024-08-29T19:12:51.113Z,1724958771.113 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2024-08-29T19:12:51.507Z,1724958771.507 [Reporter](INFO): platform_buoyancy_position 934.986572 cc
2024-08-29T19:12:51.907Z,1724958771.907 [Reporter](INFO): platform_buoyancy_position 930.823242 cc
2024-08-29T19:12:52.307Z,1724958772.307 [Reporter](INFO): platform_buoyancy_position 927.734375 cc
2024-08-29T19:12:52.727Z,1724958772.727 [Reporter](INFO): platform_buoyancy_position 922.228027 cc
2024-08-29T19:12:53.143Z,1724958773.143 [Reporter](INFO): platform_buoyancy_position 917.930420 cc
2024-08-29T19:12:53.539Z,1724958773.539 [Reporter](INFO): platform_buoyancy_position 911.484070 cc
2024-08-29T19:12:54.007Z,1724958774.007 [Reporter](INFO): platform_buoyancy_position 907.052124 cc
2024-08-29T19:12:54.361Z,1724958774.361 [Reporter](INFO): platform_buoyancy_position 902.754517 cc
2024-08-29T19:12:54.754Z,1724958774.754 [Reporter](INFO): platform_buoyancy_position 896.442444 cc
2024-08-29T19:12:55.298Z,1724958775.298 [Reporter](INFO): platform_buoyancy_position 892.010559 cc
2024-08-29T19:12:55.702Z,1724958775.702 [Reporter](INFO): platform_buoyancy_position 885.698425 cc
2024-08-29T19:12:56.034Z,1724958776.034 [Reporter](INFO): platform_buoyancy_position 881.400818 cc
2024-08-29T19:12:56.434Z,1724958776.434 [Reporter](INFO): platform_buoyancy_position 876.834656 cc
2024-08-29T19:12:56.846Z,1724958776.846 [Reporter](INFO): platform_buoyancy_position 870.522522 cc
2024-08-29T19:12:57.242Z,1724958777.242 [Reporter](INFO): platform_buoyancy_position 866.224915 cc
2024-08-29T19:12:57.679Z,1724958777.679 [Reporter](INFO): platform_buoyancy_position 859.778564 cc
2024-08-29T19:12:58.129Z,1724958778.129 [Reporter](INFO): platform_buoyancy_position 855.480957 cc
2024-08-29T19:12:58.742Z,1724958778.742 [Reporter](INFO): platform_buoyancy_position 849.034546 cc
2024-08-29T19:12:58.823Z,1724958778.823 [Reporter](INFO): platform_buoyancy_position 844.871216 cc
2024-08-29T19:12:59.215Z,1724958779.215 [Reporter](INFO): platform_buoyancy_position 838.424866 cc
2024-08-29T19:12:59.611Z,1724958779.611 [Reporter](INFO): platform_buoyancy_position 833.992920 cc
2024-08-29T19:13:00.043Z,1724958780.043 [Reporter](INFO): platform_buoyancy_position 827.680847 cc
2024-08-29T19:13:00.439Z,1724958780.439 [Reporter](INFO): platform_buoyancy_position 823.248962 cc
2024-08-29T19:13:00.875Z,1724958780.875 [Reporter](INFO): platform_buoyancy_position 816.802551 cc
2024-08-29T19:13:01.347Z,1724958781.347 [Reporter](INFO): platform_buoyancy_position 810.356140 cc
2024-08-29T19:13:01.637Z,1724958781.637 [Reporter](INFO): platform_buoyancy_position 806.192810 cc
2024-08-29T19:13:02.086Z,1724958782.086 [Reporter](INFO): platform_buoyancy_position 801.760925 cc
2024-08-29T19:13:02.555Z,1724958782.555 [Reporter](INFO): platform_buoyancy_position 795.314514 cc
2024-08-29T19:13:02.883Z,1724958782.883 [Reporter](INFO): platform_buoyancy_position 791.151245 cc
2024-08-29T19:13:03.306Z,1724958783.306 [Reporter](INFO): platform_buoyancy_position 784.704834 cc
2024-08-29T19:13:03.711Z,1724958783.711 [Reporter](INFO): platform_buoyancy_position 780.138611 cc
2024-08-29T19:13:04.067Z,1724958784.067 [Reporter](INFO): platform_buoyancy_position 773.826538 cc
2024-08-29T19:13:04.464Z,1724958784.464 [Reporter](INFO): platform_buoyancy_position 769.663208 cc
2024-08-29T19:13:04.883Z,1724958784.883 [Reporter](INFO): platform_buoyancy_position 765.231323 cc
2024-08-29T19:13:05.295Z,1724958785.295 [Reporter](INFO): platform_buoyancy_position 758.784912 cc
2024-08-29T19:13:05.679Z,1724958785.679 [Reporter](INFO): platform_buoyancy_position 754.487366 cc
2024-08-29T19:13:06.117Z,1724958786.117 [Reporter](INFO): platform_buoyancy_position 748.175232 cc
2024-08-29T19:13:06.495Z,1724958786.495 [Reporter](INFO): platform_buoyancy_position 743.877625 cc
2024-08-29T19:13:06.899Z,1724958786.899 [Reporter](INFO): platform_buoyancy_position 737.296936 cc
2024-08-29T19:13:07.378Z,1724958787.378 [SBIT](CRITICAL): SBIT FAILED
2024-08-29T19:13:07.378Z,1724958787.378 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-08-29T19:13:07.379Z,1724958787.379 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-08-29T19:13:07.379Z,1724958787.379 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-08-29T19:13:07.379Z,1724958787.379 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-08-29T19:13:07.379Z,1724958787.379 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-08-29T19:13:07.379Z,1724958787.379 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=254.906938 cubic_centimeter;
2024-08-29T19:13:07.379Z,1724958787.379 [SBIT](IMPORTANT): VerticalControl.massDefault=3.5 centimeter;
2024-08-29T19:13:07.381Z,1724958787.381 [CommandExec](FAULT): Scheduling is paused
2024-08-29T19:13:07.381Z,1724958787.381 [CBIT](INFO): Critical error at 20240829T191307
2024-08-29T19:13:07.381Z,1724958787.381 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-08-29T19:13:07.385Z,1724958787.385 [Reporter](INFO): platform_buoyancy_position 731.119141 cc
2024-08-29T19:13:07.701Z,1724958787.701 [MissionManager](IMPORTANT): Started mission Startup
2024-08-29T19:13:07.701Z,1724958787.701 [Startup] Running Loop=1
2024-08-29T19:13:07.702Z,1724958787.702 [Startup](DEBUG): Aggregate::initialize Startup
2024-08-29T19:13:07.702Z,1724958787.702 [Startup:A.GoToSurface] Running Loop=1
2024-08-29T19:13:07.702Z,1724958787.702 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-29T19:13:07.702Z,1724958787.702 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-29T19:13:07.703Z,1724958787.703 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-29T19:13:07.703Z,1724958787.703 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-29T19:13:07.703Z,1724958787.703 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-29T19:13:07.711Z,1724958787.711 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-29T19:13:07.716Z,1724958787.716 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-29T19:13:07.718Z,1724958787.718 [Startup:StartupSatComms] Running Loop=1
2024-08-29T19:13:07.718Z,1724958787.718 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-08-29T19:13:07.718Z,1724958787.718 [Startup:StartupSatComms:A] Running Loop=1
2024-08-29T19:13:07.771Z,1724958787.771 [Reporter](INFO): platform_buoyancy_position 726.687256 cc
2024-08-29T19:13:08.097Z,1724958788.097 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-08-29T19:13:08.139Z,1724958788.139 [Reporter](INFO): platform_buoyancy_position 720.240845 cc
2024-08-29T19:13:08.538Z,1724958788.538 [Reporter](INFO): platform_buoyancy_position 715.808960 cc
2024-08-29T19:13:08.973Z,1724958788.973 [Reporter](INFO): platform_buoyancy_position 711.511353 cc
2024-08-29T19:13:09.350Z,1724958789.350 [Reporter](INFO): platform_buoyancy_position 706.676514 cc
2024-08-29T19:13:09.770Z,1724958789.770 [Reporter](INFO): platform_buoyancy_position 700.767334 cc
2024-08-29T19:13:10.201Z,1724958790.201 [Reporter](INFO): platform_buoyancy_position 694.589539 cc
2024-08-29T19:13:10.645Z,1724958790.645 [Reporter](INFO): platform_buoyancy_position 690.157654 cc
2024-08-29T19:13:10.948Z,1724958790.948 [Reporter](INFO): platform_buoyancy_position 685.725708 cc
2024-08-29T19:13:11.394Z,1724958791.394 [Reporter](INFO): platform_buoyancy_position 679.279358 cc
2024-08-29T19:13:11.770Z,1724958791.770 [Reporter](INFO): platform_buoyancy_position 674.981750 cc
2024-08-29T19:13:12.179Z,1724958792.179 [Reporter](INFO): platform_buoyancy_position 668.535339 cc
2024-08-29T19:13:12.593Z,1724958792.593 [Reporter](INFO): platform_buoyancy_position 664.103455 cc
2024-08-29T19:13:12.975Z,1724958792.975 [Reporter](INFO): platform_buoyancy_position 658.059937 cc
2024-08-29T19:13:13.373Z,1724958793.373 [Reporter](INFO): platform_buoyancy_position 653.762329 cc
2024-08-29T19:13:13.605Z,1724958793.605 [CommandExec](IMPORTANT): got command strobe off
2024-08-29T19:13:13.605Z,1724958793.605 [CommandExec](IMPORTANT): Deactivating strobe
2024-08-29T19:13:13.782Z,1724958793.782 [Reporter](INFO): platform_buoyancy_position 647.315918 cc
2024-08-29T19:13:14.196Z,1724958794.196 [Reporter](INFO): platform_buoyancy_position 642.884033 cc
2024-08-29T19:13:14.573Z,1724958794.573 [Reporter](INFO): platform_buoyancy_position 638.049255 cc
2024-08-29T19:13:15.038Z,1724958795.038 [Reporter](INFO): platform_buoyancy_position 630.931335 cc
2024-08-29T19:13:15.391Z,1724958795.391 [Reporter](INFO): platform_buoyancy_position 625.559326 cc
2024-08-29T19:13:15.817Z,1724958795.817 [Reporter](INFO): platform_buoyancy_position 621.261719 cc
2024-08-29T19:13:16.218Z,1724958796.218 [Reporter](INFO): platform_buoyancy_position 614.949646 cc
2024-08-29T19:13:16.647Z,1724958796.647 [Reporter](INFO): platform_buoyancy_position 610.517700 cc
2024-08-29T19:13:17.026Z,1724958797.026 [Reporter](INFO): platform_buoyancy_position 604.205627 cc
2024-08-29T19:13:17.390Z,1724958797.390 [Reporter](INFO): platform_buoyancy_position 599.908020 cc
2024-08-29T19:13:17.803Z,1724958797.803 [Reporter](INFO): platform_buoyancy_position 595.610413 cc
2024-08-29T19:13:18.213Z,1724958798.213 [Reporter](INFO): platform_buoyancy_position 589.432617 cc
2024-08-29T19:13:18.610Z,1724958798.610 [Reporter](INFO): platform_buoyancy_position 584.732117 cc
2024-08-29T19:13:19.006Z,1724958799.006 [Reporter](INFO): platform_buoyancy_position 578.554321 cc
2024-08-29T19:13:19.414Z,1724958799.414 [Reporter](INFO): platform_buoyancy_position 574.122437 cc
2024-08-29T19:13:19.834Z,1724958799.834 [Reporter](INFO): platform_buoyancy_position 567.810303 cc
2024-08-29T19:13:20.226Z,1724958800.226 [Reporter](INFO): platform_buoyancy_position 563.378418 cc
2024-08-29T19:13:20.712Z,1724958800.712 [Reporter](INFO): platform_buoyancy_position 556.932007 cc
2024-08-29T19:13:21.075Z,1724958801.075 [Reporter](INFO): platform_buoyancy_position 552.634399 cc
2024-08-29T19:13:21.474Z,1724958801.474 [Reporter](INFO): platform_buoyancy_position 546.188049 cc
2024-08-29T19:13:21.890Z,1724958801.890 [Reporter](INFO): platform_buoyancy_position 541.756104 cc
2024-08-29T19:13:22.269Z,1724958802.269 [Reporter](INFO): platform_buoyancy_position 535.444031 cc
2024-08-29T19:13:22.642Z,1724958802.642 [Reporter](INFO): platform_buoyancy_position 531.012146 cc
2024-08-29T19:13:23.061Z,1724958803.061 [Reporter](INFO): platform_buoyancy_position 526.714539 cc
2024-08-29T19:13:23.454Z,1724958803.454 [Reporter](INFO): platform_buoyancy_position 520.402405 cc
2024-08-29T19:13:23.866Z,1724958803.866 [Reporter](INFO): platform_buoyancy_position 515.567627 cc
2024-08-29T19:13:24.259Z,1724958804.259 [Reporter](INFO): platform_buoyancy_position 509.524139 cc
2024-08-29T19:13:24.686Z,1724958804.686 [Reporter](INFO): platform_buoyancy_position 503.077759 cc
2024-08-29T19:13:25.070Z,1724958805.070 [Reporter](INFO): platform_buoyancy_position 498.780121 cc
2024-08-29T19:13:25.504Z,1724958805.504 [Reporter](INFO): platform_buoyancy_position 493.676758 cc
2024-08-29T19:13:25.902Z,1724958805.902 [Reporter](INFO): platform_buoyancy_position 488.170441 cc
2024-08-29T19:13:26.294Z,1724958806.294 [Reporter](INFO): platform_buoyancy_position 483.738556 cc
2024-08-29T19:13:26.702Z,1724958806.702 [Reporter](INFO): platform_buoyancy_position 477.426422 cc
2024-08-29T19:13:27.109Z,1724958807.109 [Reporter](INFO): platform_buoyancy_position 472.994537 cc
2024-08-29T19:13:27.538Z,1724958807.538 [Reporter](INFO): platform_buoyancy_position 466.682465 cc
2024-08-29T19:13:27.902Z,1724958807.902 [Reporter](INFO): platform_buoyancy_position 462.384857 cc
2024-08-29T19:13:28.368Z,1724958808.368 [Reporter](INFO): platform_buoyancy_position 457.147125 cc
2024-08-29T19:13:28.730Z,1724958808.730 [Reporter](INFO): platform_buoyancy_position 452.849518 cc
2024-08-29T19:13:29.126Z,1724958809.126 [Reporter](INFO): platform_buoyancy_position 447.208954 cc
2024-08-29T19:13:29.539Z,1724958809.539 [Reporter](INFO): platform_buoyancy_position 440.762543 cc
2024-08-29T19:13:29.934Z,1724958809.934 [Reporter](INFO): platform_buoyancy_position 436.599274 cc
2024-08-29T19:13:30.326Z,1724958810.326 [Reporter](INFO): platform_buoyancy_position 430.152863 cc
2024-08-29T19:13:30.742Z,1724958810.742 [Reporter](INFO): platform_buoyancy_position 425.049469 cc
2024-08-29T19:13:31.154Z,1724958811.154 [Reporter](INFO): platform_buoyancy_position 419.274567 cc
2024-08-29T19:13:31.551Z,1724958811.551 [Reporter](INFO): platform_buoyancy_position 414.976959 cc
2024-08-29T19:13:31.986Z,1724958811.986 [Reporter](INFO): platform_buoyancy_position 408.664856 cc
2024-08-29T19:14:07.865Z,1724958847.865 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-29T19:13:07.7Z
2024-08-29T19:14:07.865Z,1724958847.865 [Startup:StartupSatComms:A] Stopped
2024-08-29T19:14:07.865Z,1724958847.865 [Startup:StartupSatComms:B] Running Loop=1
2024-08-29T19:14:08.268Z,1724958848.268 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-29T19:14:16.475Z,1724958856.475 [DataOverHttps](INFO): Sending 92 bytes from file Logs/20240829T185444/Courier0010.lzma
2024-08-29T19:14:17.478Z,1724958857.478 [DataOverHttps](INFO): Moved sent file to Logs/20240829T185444/Courier0010.lzma.bak
2024-08-29T19:14:17.478Z,1724958857.478 [DataOverHttps](INFO): SBD MOMSN=23090011
2024-08-29T19:14:33.356Z,1724958873.356 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20240829T190919/Courier0000.lzma
2024-08-29T19:14:34.358Z,1724958874.358 [DataOverHttps](INFO): Moved sent file to Logs/20240829T190919/Courier0000.lzma.bak
2024-08-29T19:14:34.358Z,1724958874.358 [DataOverHttps](INFO): SBD MOMSN=23090017
2024-08-29T19:14:41.887Z,1724958881.887 [DVL_micro](ERROR): Failed to parse:
:SA,-23.05,+03.91,113.1
2024-08-29T19:15:07.713Z,1724958907.713 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1 centimeter persist
2024-08-29T19:15:08.132Z,1724958908.132 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-29T19:14:07.9Z
2024-08-29T19:15:08.132Z,1724958908.132 [Startup:StartupSatComms:B] Stopped
2024-08-29T19:15:08.132Z,1724958908.132 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-08-29T19:15:08.133Z,1724958908.133 [Startup:StartupSatComms] Stopped
2024-08-29T19:15:08.133Z,1724958908.133 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-08-29T19:15:08.134Z,1724958908.134 [Startup](INFO): Completed Startup
2024-08-29T19:15:08.134Z,1724958908.134 [MissionManager](INFO): Startup is completed.
2024-08-29T19:15:08.134Z,1724958908.134 [MissionManager](INFO): Uninitializing Mission Startup
2024-08-29T19:15:08.134Z,1724958908.134 [Startup] Stopped
2024-08-29T19:15:08.134Z,1724958908.134 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-08-29T19:15:08.134Z,1724958908.134 [Startup:A.GoToSurface] Stopped
2024-08-29T19:15:08.134Z,1724958908.134 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-29T19:15:08.398Z,1724958908.398 [DataOverHttps](INFO): Sending 550 bytes from file Logs/20240829T185444/Express0011.lzma
2024-08-29T19:15:08.626Z,1724958908.626 [MissionManager](IMPORTANT): Started mission Default
2024-08-29T19:15:08.626Z,1724958908.626 [Default] Running Loop=1
2024-08-29T19:15:08.626Z,1724958908.626 [Default](DEBUG): Aggregate::initialize Default
2024-08-29T19:15:08.627Z,1724958908.627 [Default:B.GoToSurface] Running Loop=1
2024-08-29T19:15:08.627Z,1724958908.627 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-29T19:15:08.627Z,1724958908.627 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-29T19:15:08.627Z,1724958908.627 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-29T19:15:08.627Z,1724958908.627 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-29T19:15:08.628Z,1724958908.628 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-29T19:15:08.629Z,1724958908.629 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-29T19:15:08.629Z,1724958908.629 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-29T19:15:08.629Z,1724958908.629 [Default:A.Wait] Running Loop=1
2024-08-29T19:15:08.629Z,1724958908.629 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-08-29T19:15:08.706Z,1724958908.706 [DataOverHttps](INFO): Moved sent file to Logs/20240829T185444/Express0011.lzma.bak
2024-08-29T19:15:08.706Z,1724958908.706 [DataOverHttps](INFO): SBD MOMSN=23090023
2024-08-29T19:15:15.755Z,1724958915.755 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-08-29T19:15:19.402Z,1724958919.402 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191513.00,A,3648.16819,N,12147.28286,W,0.019,189.50,290824,,,A*73
2024-08-29T19:15:19.405Z,1724958919.405 [NAL9602](INFO): GPS fix at 20240829T191513: (36.802803, -121.788048)
2024-08-29T19:15:21.832Z,1724958921.832 [Default:A.Wait](INFO): Done Waiting.
2024-08-29T19:15:21.832Z,1724958921.832 [Default:A.Wait] Stopped
2024-08-29T19:15:21.832Z,1724958921.832 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-08-29T19:15:22.318Z,1724958922.318 [Default:CheckIn] Running Loop=1
2024-08-29T19:15:22.318Z,1724958922.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-29T19:15:22.318Z,1724958922.318 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-29T19:15:22.668Z,1724958922.668 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-08-29T19:15:24.255Z,1724958924.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191518.00,A,3648.17017,N,12147.28367,W,0.369,189.50,290824,,,A*75
2024-08-29T19:15:24.273Z,1724958924.273 [NAL9602](INFO): GPS fix at 20240829T191518: (36.802836, -121.788061)
2024-08-29T19:15:24.282Z,1724958924.282 [Default:CheckIn:Read_GPS] Stopped
2024-08-29T19:15:24.282Z,1724958924.282 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-29T19:15:24.687Z,1724958924.687 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-29T19:15:33.218Z,1724958933.218 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20240829T190919/Courier0004.lzma
2024-08-29T19:15:34.218Z,1724958934.218 [DataOverHttps](INFO): Moved sent file to Logs/20240829T190919/Courier0004.lzma.bak
2024-08-29T19:15:34.218Z,1724958934.218 [DataOverHttps](INFO): SBD MOMSN=23090045
2024-08-29T19:15:57.009Z,1724958957.009 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-29T19:16:20.661Z,1724958980.661 [CommandExec](IMPORTANT): got command strobe off
2024-08-29T19:16:20.661Z,1724958980.661 [CommandExec](IMPORTANT): Deactivating strobe
2024-08-29T19:16:31.614Z,1724958991.614 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.5 centimeter persist
2024-08-29T19:16:34.081Z,1724958994.081 [DataOverHttps](INFO): Sending 977 bytes from file Logs/20240829T190919/Express0001.lzma
2024-08-29T19:16:35.082Z,1724958995.082 [DataOverHttps](INFO): Moved sent file to Logs/20240829T190919/Express0001.lzma.bak
2024-08-29T19:16:35.082Z,1724958995.082 [DataOverHttps](INFO): SBD MOMSN=23090067
2024-08-29T19:17:41.984Z,1724959061.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-29T19:18:09.577Z,1724959089.577 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2024-08-29T19:18:09.578Z,1724959089.578 [BPC1](ERROR): Battery stick #46 (s/n: 049A) reported OVER_TEMP_ALARM. Status code: 0x1717.
2024-08-29T19:18:09.579Z,1724959089.579 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2024-08-29T19:18:09.582Z,1724959089.582 [BPC1](INFO): Received data from all battery sticks.
2024-08-29T19:18:13.124Z,1724959093.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-29T19:18:44.248Z,1724959124.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-29T19:19:15.372Z,1724959155.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-29T19:19:35.469Z,1724959175.469 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.387695
2024-08-29T19:19:36.439Z,1724959176.439 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2024-08-29T19:19:36.516Z,1724959176.516 [NAL9602](ERROR): received:
+CSQ:0
OK
2024-08-29T19:19:45.404Z,1724959185.404 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002852
2024-08-29T19:19:58.601Z,1724959198.601 [DataOverHttps](INFO): Sending 388 bytes from file Logs/20240829T190919/Express0005.lzma
2024-08-29T19:19:59.602Z,1724959199.602 [DataOverHttps](INFO): Moved sent file to Logs/20240829T190919/Express0005.lzma.bak
2024-08-29T19:19:59.602Z,1724959199.602 [DataOverHttps](INFO): SBD MOMSN=23090101
2024-08-29T19:20:02.773Z,1724959202.773 [Default:CheckIn:Read_Iridium] Stopped
2024-08-29T19:20:02.773Z,1724959202.773 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-29T19:20:02.773Z,1724959202.773 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-29T19:20:11.356Z,1724959211.356 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.7 centimeter persist
2024-08-29T19:20:16.521Z,1724959216.521 [NAL9602](INFO): Not Powering down - fast GPS
2024-08-29T19:21:13.148Z,1724959273.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-08-29T19:21:42.992Z,1724959302.992 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter
2024-08-29T19:21:45.434Z,1724959305.434 [Reporter](INFO): platform_buoyancy_position 403.964355 cc
2024-08-29T19:21:45.818Z,1724959305.818 [Reporter](INFO): platform_buoyancy_position 404.232941 cc
2024-08-29T19:21:46.214Z,1724959306.214 [Reporter](INFO): platform_buoyancy_position 402.218445 cc
2024-08-29T19:21:46.635Z,1724959306.635 [Reporter](INFO): platform_buoyancy_position 395.637726 cc
2024-08-29T19:21:47.049Z,1724959307.049 [Reporter](INFO): platform_buoyancy_position 391.474426 cc
2024-08-29T19:21:47.462Z,1724959307.462 [Reporter](INFO): platform_buoyancy_position 387.176849 cc
2024-08-29T19:21:47.874Z,1724959307.874 [Reporter](INFO): platform_buoyancy_position 382.744934 cc
2024-08-29T19:21:48.310Z,1724959308.310 [Reporter](INFO): platform_buoyancy_position 376.298553 cc
2024-08-29T19:21:48.691Z,1724959308.691 [Reporter](INFO): platform_buoyancy_position 372.135254 cc
2024-08-29T19:21:49.046Z,1724959309.046 [Reporter](INFO): platform_buoyancy_position 367.837646 cc
2024-08-29T19:21:49.505Z,1724959309.505 [Reporter](INFO): platform_buoyancy_position 362.331329 cc
2024-08-29T19:21:49.866Z,1724959309.866 [Reporter](INFO): platform_buoyancy_position 356.959351 cc
2024-08-29T19:21:50.296Z,1724959310.296 [Reporter](INFO): platform_buoyancy_position 352.258850 cc
2024-08-29T19:21:50.694Z,1724959310.694 [Reporter](INFO): platform_buoyancy_position 346.215332 cc
2024-08-29T19:21:51.090Z,1724959311.090 [Reporter](INFO): platform_buoyancy_position 341.783447 cc
2024-08-29T19:21:51.474Z,1724959311.474 [Reporter](INFO): platform_buoyancy_position 335.605621 cc
2024-08-29T19:21:51.870Z,1724959311.870 [Reporter](INFO): platform_buoyancy_position 331.173737 cc
2024-08-29T19:21:52.310Z,1724959312.310 [Reporter](INFO): platform_buoyancy_position 324.861633 cc
2024-08-29T19:21:52.670Z,1724959312.670 [Reporter](INFO): platform_buoyancy_position 320.429749 cc
2024-08-29T19:21:53.070Z,1724959313.070 [Reporter](INFO): platform_buoyancy_position 315.997833 cc
2024-08-29T19:21:53.506Z,1724959313.506 [Reporter](INFO): platform_buoyancy_position 309.820038 cc
2024-08-29T19:21:53.878Z,1724959313.878 [Reporter](INFO): platform_buoyancy_position 305.388123 cc
2024-08-29T19:21:54.302Z,1724959314.302 [Reporter](INFO): platform_buoyancy_position 298.941742 cc
2024-08-29T19:21:54.702Z,1724959314.702 [Reporter](INFO): platform_buoyancy_position 294.778442 cc
2024-08-29T19:21:55.098Z,1724959315.098 [Reporter](INFO): platform_buoyancy_position 288.466339 cc
2024-08-29T19:21:55.526Z,1724959315.526 [Reporter](INFO): platform_buoyancy_position 283.900146 cc
2024-08-29T19:21:55.902Z,1724959315.902 [Reporter](INFO): platform_buoyancy_position 278.125244 cc
2024-08-29T19:21:56.318Z,1724959316.318 [Reporter](INFO): platform_buoyancy_position 273.156128 cc
2024-08-29T19:21:56.715Z,1724959316.715 [Reporter](INFO): platform_buoyancy_position 268.187042 cc
2024-08-29T19:21:57.141Z,1724959317.141 [Reporter](INFO): platform_buoyancy_position 262.412140 cc
2024-08-29T19:21:57.533Z,1724959317.533 [Reporter](INFO): platform_buoyancy_position 256.100037 cc
2024-08-29T19:21:57.958Z,1724959317.958 [Reporter](INFO): platform_buoyancy_position 251.668121 cc
2024-08-29T19:21:58.354Z,1724959318.354 [Reporter](INFO): platform_buoyancy_position 245.490311 cc
2024-08-29T19:21:58.734Z,1724959318.734 [Reporter](INFO): platform_buoyancy_position 241.058441 cc
2024-08-29T19:21:59.162Z,1724959319.162 [Reporter](INFO): platform_buoyancy_position 236.357941 cc
2024-08-29T19:21:59.569Z,1724959319.569 [Reporter](INFO): platform_buoyancy_position 230.180115 cc
2024-08-29T19:21:59.942Z,1724959319.942 [Reporter](INFO): platform_buoyancy_position 226.016815 cc
2024-08-29T19:22:00.358Z,1724959320.358 [Reporter](INFO): platform_buoyancy_position 219.570435 cc
2024-08-29T19:22:00.765Z,1724959320.765 [Reporter](INFO): platform_buoyancy_position 215.138519 cc
2024-08-29T19:22:01.204Z,1724959321.204 [Reporter](INFO): platform_buoyancy_position 208.960724 cc
2024-08-29T19:23:00.073Z,1724959380.073 [CommandExec](IMPORTANT): got command maintain clear
2024-08-29T19:23:00.186Z,1724959380.186 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-29T19:23:02.622Z,1724959382.622 [Reporter](INFO): platform_buoyancy_position 202.245728 cc
2024-08-29T19:23:03.378Z,1724959383.378 [Reporter](INFO): platform_buoyancy_position 204.394531 cc
2024-08-29T19:23:03.802Z,1724959383.802 [Reporter](INFO): platform_buoyancy_position 206.677612 cc
2024-08-29T19:23:04.194Z,1724959384.194 [Reporter](INFO): platform_buoyancy_position 210.975220 cc
2024-08-29T19:23:04.594Z,1724959384.594 [Reporter](INFO): platform_buoyancy_position 215.272827 cc
2024-08-29T19:23:04.998Z,1724959384.998 [Reporter](INFO): platform_buoyancy_position 221.584930 cc
2024-08-29T19:23:05.418Z,1724959385.418 [Reporter](INFO): platform_buoyancy_position 226.016815 cc
2024-08-29T19:23:05.822Z,1724959385.822 [Reporter](INFO): platform_buoyancy_position 232.194641 cc
2024-08-29T19:23:06.197Z,1724959386.197 [Reporter](INFO): platform_buoyancy_position 236.760834 cc
2024-08-29T19:23:06.621Z,1724959386.621 [Reporter](INFO): platform_buoyancy_position 242.804321 cc
2024-08-29T19:23:07.010Z,1724959387.010 [Reporter](INFO): platform_buoyancy_position 247.639145 cc
2024-08-29T19:23:07.449Z,1724959387.449 [Reporter](INFO): platform_buoyancy_position 253.816925 cc
2024-08-29T19:23:07.826Z,1724959387.826 [Reporter](INFO): platform_buoyancy_position 258.248840 cc
2024-08-29T19:23:08.234Z,1724959388.234 [Reporter](INFO): platform_buoyancy_position 262.546417 cc
2024-08-29T19:23:08.699Z,1724959388.699 [Reporter](INFO): platform_buoyancy_position 269.127136 cc
2024-08-29T19:23:09.026Z,1724959389.026 [Reporter](INFO): platform_buoyancy_position 273.156128 cc
2024-08-29T19:23:09.474Z,1724959389.474 [Reporter](INFO): platform_buoyancy_position 279.736847 cc
2024-08-29T19:23:09.846Z,1724959389.846 [Reporter](INFO): platform_buoyancy_position 284.034424 cc
2024-08-29T19:23:10.242Z,1724959390.242 [Reporter](INFO): platform_buoyancy_position 290.346527 cc
2024-08-29T19:23:10.678Z,1724959390.678 [Reporter](INFO): platform_buoyancy_position 294.644135 cc
2024-08-29T19:23:11.043Z,1724959391.043 [Reporter](INFO): platform_buoyancy_position 301.090546 cc
2024-08-29T19:23:11.470Z,1724959391.470 [Reporter](INFO): platform_buoyancy_position 305.388123 cc
2024-08-29T19:23:11.886Z,1724959391.886 [Reporter](INFO): platform_buoyancy_position 309.820038 cc
2024-08-29T19:23:12.281Z,1724959392.281 [Reporter](INFO): platform_buoyancy_position 316.266449 cc
2024-08-29T19:23:12.694Z,1724959392.694 [Reporter](INFO): platform_buoyancy_position 322.712830 cc
2024-08-29T19:23:13.074Z,1724959393.074 [Reporter](INFO): platform_buoyancy_position 327.010437 cc
2024-08-29T19:23:13.497Z,1724959393.497 [Reporter](INFO): platform_buoyancy_position 331.173737 cc
2024-08-29T19:23:13.886Z,1724959393.886 [Reporter](INFO): platform_buoyancy_position 337.620148 cc
2024-08-29T19:23:14.284Z,1724959394.284 [Reporter](INFO): platform_buoyancy_position 342.052032 cc
2024-08-29T19:23:14.690Z,1724959394.690 [Reporter](INFO): platform_buoyancy_position 348.364136 cc
2024-08-29T19:23:15.086Z,1724959395.086 [Reporter](INFO): platform_buoyancy_position 353.601837 cc
2024-08-29T19:23:15.518Z,1724959395.518 [Reporter](INFO): platform_buoyancy_position 359.376740 cc
2024-08-29T19:23:15.894Z,1724959395.894 [Reporter](INFO): platform_buoyancy_position 363.540039 cc
2024-08-29T19:23:16.319Z,1724959396.319 [Reporter](INFO): platform_buoyancy_position 369.852142 cc
2024-08-29T19:23:16.730Z,1724959396.730 [Reporter](INFO): platform_buoyancy_position 374.284027 cc
2024-08-29T19:23:17.106Z,1724959397.106 [Reporter](INFO): platform_buoyancy_position 379.253143 cc
2024-08-29T19:23:17.522Z,1724959397.522 [Reporter](INFO): platform_buoyancy_position 385.028046 cc
2024-08-29T19:23:17.918Z,1724959397.918 [Reporter](INFO): platform_buoyancy_position 391.340149 cc
2024-08-29T19:23:18.354Z,1724959398.354 [Reporter](INFO): platform_buoyancy_position 395.906342 cc
2024-08-29T19:23:18.735Z,1724959398.735 [Reporter](INFO): platform_buoyancy_position 402.084137 cc
2024-08-29T19:23:19.142Z,1724959399.142 [Reporter](INFO): platform_buoyancy_position 406.381744 cc
2024-08-29T19:23:19.554Z,1724959399.554 [Reporter](INFO): platform_buoyancy_position 411.082245 cc
2024-08-29T19:23:19.943Z,1724959399.943 [Reporter](INFO): platform_buoyancy_position 417.125763 cc
2024-08-29T19:23:20.378Z,1724959400.378 [Reporter](INFO): platform_buoyancy_position 423.572113 cc
2024-08-29T19:23:20.754Z,1724959400.754 [Reporter](INFO): platform_buoyancy_position 427.869720 cc
2024-08-29T19:23:21.154Z,1724959401.154 [Reporter](INFO): platform_buoyancy_position 432.301666 cc
2024-08-29T19:23:21.579Z,1724959401.579 [Reporter](INFO): platform_buoyancy_position 438.748016 cc
2024-08-29T19:23:21.959Z,1724959401.959 [Reporter](INFO): platform_buoyancy_position 443.045624 cc
2024-08-29T19:23:22.377Z,1724959402.377 [Reporter](INFO): platform_buoyancy_position 449.357758 cc
2024-08-29T19:23:22.766Z,1724959402.766 [Reporter](INFO): platform_buoyancy_position 453.789642 cc
2024-08-29T19:23:23.199Z,1724959403.199 [Reporter](INFO): platform_buoyancy_position 460.236053 cc
2024-08-29T19:23:23.585Z,1724959403.585 [Reporter](INFO): platform_buoyancy_position 464.533661 cc
2024-08-29T19:23:23.986Z,1724959403.986 [Reporter](INFO): platform_buoyancy_position 470.577118 cc
2024-08-29T19:23:24.399Z,1724959404.399 [Reporter](INFO): platform_buoyancy_position 475.277618 cc
2024-08-29T19:23:24.794Z,1724959404.794 [Reporter](INFO): platform_buoyancy_position 480.381073 cc
2024-08-29T19:23:25.198Z,1724959405.198 [Reporter](INFO): platform_buoyancy_position 485.887360 cc
2024-08-29T19:23:25.607Z,1724959405.607 [Reporter](INFO): platform_buoyancy_position 490.319214 cc
2024-08-29T19:23:26.011Z,1724959406.011 [Reporter](INFO): platform_buoyancy_position 496.765656 cc
2024-08-29T19:23:26.430Z,1724959406.430 [Reporter](INFO): platform_buoyancy_position 503.077759 cc
2024-08-29T19:23:26.806Z,1724959406.806 [Reporter](INFO): platform_buoyancy_position 507.375336 cc
2024-08-29T19:23:27.230Z,1724959407.230 [Reporter](INFO): platform_buoyancy_position 513.821716 cc
2024-08-29T19:23:27.618Z,1724959407.618 [Reporter](INFO): platform_buoyancy_position 518.119324 cc
2024-08-29T19:23:28.052Z,1724959408.052 [Reporter](INFO): platform_buoyancy_position 523.625610 cc
2024-08-29T19:23:28.458Z,1724959408.458 [Reporter](INFO): platform_buoyancy_position 528.997620 cc
2024-08-29T19:23:28.826Z,1724959408.826 [Reporter](INFO): platform_buoyancy_position 533.295227 cc
2024-08-29T19:23:29.253Z,1724959409.253 [Reporter](INFO): platform_buoyancy_position 539.607300 cc
2024-08-29T19:23:29.658Z,1724959409.658 [Reporter](INFO): platform_buoyancy_position 544.039246 cc
2024-08-29T19:23:30.050Z,1724959410.050 [Reporter](INFO): platform_buoyancy_position 550.351318 cc
2024-08-29T19:23:30.478Z,1724959410.478 [Reporter](INFO): platform_buoyancy_position 554.783203 cc
2024-08-29T19:23:30.857Z,1724959410.857 [Reporter](INFO): platform_buoyancy_position 559.618042 cc
2024-08-29T19:23:31.282Z,1724959411.282 [Reporter](INFO): platform_buoyancy_position 565.392944 cc
2024-08-29T19:23:31.662Z,1724959411.662 [Reporter](INFO): platform_buoyancy_position 570.630615 cc
2024-08-29T19:23:32.062Z,1724959412.062 [Reporter](INFO): platform_buoyancy_position 576.136902 cc
2024-08-29T19:23:32.479Z,1724959412.479 [Reporter](INFO): platform_buoyancy_position 580.434509 cc
2024-08-29T19:23:32.885Z,1724959412.885 [Reporter](INFO): platform_buoyancy_position 587.015198 cc
2024-08-29T19:23:33.298Z,1724959413.298 [Reporter](INFO): platform_buoyancy_position 591.312805 cc
2024-08-29T19:23:33.714Z,1724959413.714 [Reporter](INFO): platform_buoyancy_position 597.759216 cc
2024-08-29T19:23:34.147Z,1724959414.147 [Reporter](INFO): platform_buoyancy_position 602.191101 cc
2024-08-29T19:23:34.555Z,1724959414.555 [Reporter](INFO): platform_buoyancy_position 608.503235 cc
2024-08-29T19:23:34.926Z,1724959414.926 [Reporter](INFO): platform_buoyancy_position 612.666504 cc
2024-08-29T19:23:35.419Z,1724959415.419 [Reporter](INFO): platform_buoyancy_position 619.247253 cc
2024-08-29T19:23:35.765Z,1724959415.765 [Reporter](INFO): platform_buoyancy_position 625.425049 cc
2024-08-29T19:23:36.233Z,1724959416.233 [Reporter](INFO): platform_buoyancy_position 629.991211 cc
2024-08-29T19:23:36.624Z,1724959416.624 [Reporter](INFO): platform_buoyancy_position 636.437622 cc
2024-08-29T19:23:36.988Z,1724959416.988 [Reporter](INFO): platform_buoyancy_position 640.735229 cc
2024-08-29T19:23:37.433Z,1724959417.433 [Reporter](INFO): platform_buoyancy_position 647.047302 cc
2024-08-29T19:23:37.763Z,1724959417.763 [Reporter](INFO): platform_buoyancy_position 651.479248 cc
2024-08-29T19:23:38.230Z,1724959418.230 [Reporter](INFO): platform_buoyancy_position 657.119812 cc
2024-08-29T19:23:38.605Z,1724959418.605 [Reporter](INFO): platform_buoyancy_position 662.088928 cc
2024-08-29T19:23:38.953Z,1724959418.953 [Reporter](INFO): platform_buoyancy_position 666.520813 cc
2024-08-29T19:23:39.447Z,1724959419.447 [Reporter](INFO): platform_buoyancy_position 672.967224 cc
2024-08-29T19:23:39.750Z,1724959419.750 [Reporter](INFO): platform_buoyancy_position 677.130554 cc
2024-08-29T19:23:40.279Z,1724959420.279 [Reporter](INFO): platform_buoyancy_position 683.576904 cc
2024-08-29T19:23:40.562Z,1724959420.562 [Reporter](INFO): platform_buoyancy_position 687.740234 cc
2024-08-29T19:23:40.950Z,1724959420.950 [Reporter](INFO): platform_buoyancy_position 692.172119 cc
2024-08-29T19:23:41.366Z,1724959421.366 [Reporter](INFO): platform_buoyancy_position 697.812744 cc
2024-08-29T19:23:41.762Z,1724959421.762 [Reporter](INFO): platform_buoyancy_position 703.050415 cc
2024-08-29T19:23:42.197Z,1724959422.197 [Reporter](INFO): platform_buoyancy_position 709.362549 cc
2024-08-29T19:23:42.563Z,1724959422.563 [Reporter](INFO): platform_buoyancy_position 713.660156 cc
2024-08-29T19:23:42.982Z,1724959422.982 [Reporter](INFO): platform_buoyancy_position 718.092041 cc
2024-08-29T19:23:43.399Z,1724959423.399 [Reporter](INFO): platform_buoyancy_position 724.538452 cc
2024-08-29T19:23:43.770Z,1724959423.770 [Reporter](INFO): platform_buoyancy_position 728.970337 cc
2024-08-29T19:23:44.186Z,1724959424.186 [Reporter](INFO): platform_buoyancy_position 735.013855 cc
2024-08-29T19:23:44.594Z,1724959424.594 [Reporter](INFO): platform_buoyancy_position 739.714355 cc
2024-08-29T19:23:45.018Z,1724959425.018 [Reporter](INFO): platform_buoyancy_position 745.892151 cc
2024-08-29T19:23:45.423Z,1724959425.423 [Reporter](INFO): platform_buoyancy_position 750.324036 cc
2024-08-29T19:23:45.795Z,1724959425.795 [Reporter](INFO): platform_buoyancy_position 756.636108 cc
2024-08-29T19:23:46.210Z,1724959426.210 [Reporter](INFO): platform_buoyancy_position 761.068054 cc
2024-08-29T19:23:46.606Z,1724959426.606 [Reporter](INFO): platform_buoyancy_position 767.380127 cc
2024-08-29T19:23:47.047Z,1724959427.047 [Reporter](INFO): platform_buoyancy_position 771.812012 cc
2024-08-29T19:23:47.434Z,1724959427.434 [Reporter](INFO): platform_buoyancy_position 778.124146 cc
2024-08-29T19:23:47.832Z,1724959427.832 [Reporter](INFO): platform_buoyancy_position 782.421753 cc
2024-08-29T19:23:48.249Z,1724959428.249 [Reporter](INFO): platform_buoyancy_position 788.868164 cc
2024-08-29T19:23:48.622Z,1724959428.622 [Reporter](INFO): platform_buoyancy_position 793.300049 cc
2024-08-29T19:23:49.070Z,1724959429.070 [Reporter](INFO): platform_buoyancy_position 799.746460 cc
2024-08-29T19:23:49.437Z,1724959429.437 [CommandExec](IMPORTANT): got command report clear
2024-08-29T19:24:07.220Z,1724959447.220 [CommandExec](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 200 cubic_centimeter persist
2024-08-29T19:24:08.921Z,1724959448.921 [CommandExec](IMPORTANT): got command gfscan
2024-08-29T19:24:09.207Z,1724959449.207 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-29T19:24:20.127Z,1724959460.127 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.805232
CHAN A1 (24V): 0.755263
CHAN A2 (12V): 0.647267
CHAN A3 (5V): 0.250296
CHAN B0 (3.3V): 0.165408
CHAN B1 (3.15aV): 0.183265
CHAN B2 (3.15bV): 0.185766
CHAN B3 (GND): 0.017085
OPEN: 0.027536
Full Scale: +/- 1 mA
2024-08-29T19:25:03.407Z,1724959503.407 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-29T19:25:03.407Z,1724959503.407 [Default:CheckIn:C.Wait] Stopped
2024-08-29T19:25:03.407Z,1724959503.407 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-29T19:25:03.407Z,1724959503.407 [Default:CheckIn:D] Running Loop=1
2024-08-29T19:25:03.731Z,1724959503.731 [Default:CheckIn:D] Stopped
2024-08-29T19:25:03.731Z,1724959503.731 [Default:CheckIn:E] Running Loop=1
2024-08-29T19:25:04.128Z,1724959504.128 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.918413 min
2024-08-29T19:25:04.128Z,1724959504.128 [Default:CheckIn:E] Stopped
2024-08-29T19:25:04.128Z,1724959504.128 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-29T19:25:04.128Z,1724959504.128 [Default:CheckIn] Stopped
2024-08-29T19:25:04.129Z,1724959504.129 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-29T19:25:04.129Z,1724959504.129 [Default:CheckIn](INFO): Running loop #2
2024-08-29T19:25:04.129Z,1724959504.129 [Default:CheckIn] Running Loop=2
2024-08-29T19:25:04.129Z,1724959504.129 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-29T19:25:04.129Z,1724959504.129 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-29T19:25:06.148Z,1724959506.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192502.00,A,3648.16840,N,12147.28479,W,0.156,345.65,290824,,,A*74
2024-08-29T19:25:06.151Z,1724959506.151 [NAL9602](INFO): GPS fix at 20240829T192502: (36.802807, -121.788080)
2024-08-29T19:25:06.182Z,1724959506.182 [Default:CheckIn:Read_GPS] Stopped
2024-08-29T19:25:06.182Z,1724959506.182 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-29T19:25:14.535Z,1724959514.535 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240829T190919/Courier0007.lzma
2024-08-29T19:25:15.538Z,1724959515.538 [DataOverHttps](INFO): Moved sent file to Logs/20240829T190919/Courier0007.lzma.bak
2024-08-29T19:25:15.538Z,1724959515.538 [DataOverHttps](INFO): SBD MOMSN=23090117
2024-08-29T19:25:28.361Z,1724959528.361 [CommandExec](IMPORTANT): got command gfscan
2024-08-29T19:25:28.429Z,1724959528.429 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-29T19:25:33.624Z,1724959533.624 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2024-08-29T19:25:33.624Z,1724959533.624 [AHRS_M2] Data Fault, FailCount= 1
2024-08-29T19:25:33.624Z,1724959533.624 [AHRS_M2](ERROR): Data Fault
2024-08-29T19:25:33.636Z,1724959533.636 [DataOverHttps](INFO): Sending 598 bytes from file Logs/20240829T190919/Express0008.lzma
2024-08-29T19:25:33.709Z,1724959533.709 [CBIT](ERROR): Data Fault in component: AHRS_M2
2024-08-29T19:25:34.008Z,1724959534.008 [AHRS_M2](INFO): Powering down
2024-08-29T19:25:34.638Z,1724959534.638 [DataOverHttps](INFO): Moved sent file to Logs/20240829T190919/Express0008.lzma.bak
2024-08-29T19:25:34.638Z,1724959534.638 [DataOverHttps](INFO): SBD MOMSN=23090120
2024-08-29T19:25:34.899Z,1724959534.899 [CBIT](INFO): Clearing failed state for component AHRS_M2
2024-08-29T19:25:34.899Z,1724959534.899 [AHRS_M2] No Fault, FailCount= 1
2024-08-29T19:25:35.216Z,1724959535.216 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-29T19:25:39.367Z,1724959539.367 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.810860
CHAN A1 (24V): 0.758909
CHAN A2 (12V): 0.661438
CHAN A3 (5V): 0.253640
CHAN B0 (3.3V): 0.164299
CHAN B1 (3.15aV): 0.181229
CHAN B2 (3.15bV): 0.181361
CHAN B3 (GND): 0.017191
OPEN: 0.027756
Full Scale: +/- 1 mA
2024-08-29T19:25:39.747Z,1724959539.747 [Default:CheckIn:Read_Iridium] Stopped
2024-08-29T19:25:39.747Z,1724959539.747 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-29T19:25:39.768Z,1724959539.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-29T19:25:52.213Z,1724959552.213 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (1 active estimators).
2024-08-29T19:26:05.129Z,1724959565.129 [DVL_micro](ERROR): No DVL communication! Re-initializing
2024-08-29T19:26:05.129Z,1724959565.129 [DVL_micro] Communications Fault, FailCount= 1
2024-08-29T19:26:05.129Z,1724959565.129 [DVL_micro](ERROR): Communications Fault
2024-08-29T19:26:05.271Z,1724959565.271 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-08-29T19:26:05.632Z,1724959565.632 [DVL_micro](INFO): Powering down
2024-08-29T19:26:06.174Z,1724959566.174 [CommandExec](IMPORTANT): got command gfscan
2024-08-29T19:26:06.485Z,1724959566.485 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-08-29T19:26:06.485Z,1724959566.485 [DVL_micro] No Fault, FailCount= 1
2024-08-29T19:26:06.485Z,1724959566.485 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-29T19:26:08.763Z,1724959568.763 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.643573 decibar, -0.638827 m
2024-08-29T19:26:11.594Z,1724959571.594 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.621421 decibar, -0.616837 m
2024-08-29T19:26:13.606Z,1724959573.606 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples
2024-08-29T19:26:13.606Z,1724959573.606 [Depth_Keller] Data Fault, FailCount= 1
2024-08-29T19:26:13.606Z,1724959573.606 [Depth_Keller](ERROR): Data Fault
2024-08-29T19:26:13.688Z,1724959573.688 [CBIT](ERROR): Data Fault in component: Depth_Keller
2024-08-29T19:26:13.688Z,1724959573.688 [CBIT](INFO): Clearing failed state for component Depth_Keller
2024-08-29T19:26:13.689Z,1724959573.689 [Depth_Keller] No Fault, FailCount= 1
2024-08-29T19:26:14.419Z,1724959574.419 [Depth_Keller](ERROR): Pressure or depth reading out of range: -3.400027 decibar, -3.374973 m
2024-08-29T19:26:17.804Z,1724959577.804 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.807939
CHAN A1 (24V): 0.047187
CHAN A2 (12V): 0.651921
CHAN A3 (5V): 0.249990
CHAN B0 (3.3V): 0.162469
CHAN B1 (3.15aV): 0.178150
CHAN B2 (3.15bV): 0.177865
CHAN B3 (GND): 0.015812
OPEN: 0.025182
Full Scale: +/- 1 mA
2024-08-29T19:26:24.111Z,1724959584.111 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-08-29T19:26:24.111Z,1724959584.111 [DVL_micro] Communications Fault, FailCount= 2
2024-08-29T19:26:24.111Z,1724959584.111 [DVL_micro](ERROR): Communications Fault
2024-08-29T19:26:24.165Z,1724959584.165 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-08-29T19:26:24.609Z,1724959584.609 [DVL_micro](INFO): Poweri