2024-06-07T08:40:02.211Z,1717749602.211 [Supervisor](DEBUG): Initializing supervisor.
2024-06-07T08:40:02.215Z,1717749602.215 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-07T08:40:02.216Z,1717749602.216 [SyncHandler](INFO): Protected caller Thread ID is 1012
2024-06-07T08:40:02.216Z,1717749602.216 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-07T08:40:02.217Z,1717749602.217 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-07T08:40:02.218Z,1717749602.218 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1013
2024-06-07T08:40:02.221Z,1717749602.221 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-07T08:40:02.239Z,1717749602.239 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-07T08:40:02.240Z,1717749602.240 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-07T08:40:02.240Z,1717749602.240 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1014
2024-06-07T08:40:02.245Z,1717749602.245 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-07T08:40:02.245Z,1717749602.245 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-07T08:40:02.246Z,1717749602.246 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1015
2024-06-07T08:40:02.248Z,1717749602.248 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-07T08:40:02.249Z,1717749602.249 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-07T08:40:02.249Z,1717749602.249 [logger ThreadHandler](INFO): Protected caller Thread ID is 1016
2024-06-07T08:40:02.253Z,1717749602.253 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-07T08:40:02.253Z,1717749602.253 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-07T08:40:02.255Z,1717749602.255 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-07T08:40:02.361Z,1717749602.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-07T08:40:02.361Z,1717749602.361 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-07T08:40:02.607Z,1717749602.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-07T08:40:02.608Z,1717749602.608 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-07T08:40:02.885Z,1717749602.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-07T08:40:02.885Z,1717749602.885 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-07T08:40:03.005Z,1717749603.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-07T08:40:03.006Z,1717749603.006 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-07T08:40:03.774Z,1717749603.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-07T08:40:03.775Z,1717749603.775 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-07T08:40:04.296Z,1717749604.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-07T08:40:04.297Z,1717749604.297 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-07T08:40:04.976Z,1717749604.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-07T08:40:04.976Z,1717749604.976 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-07T08:40:05.641Z,1717749605.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-07T08:40:05.641Z,1717749605.641 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-07T08:40:05.998Z,1717749605.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-07T08:40:05.998Z,1717749605.998 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-07T08:40:06.389Z,1717749606.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-07T08:40:06.389Z,1717749606.389 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-07T08:40:06.633Z,1717749606.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-07T08:40:06.633Z,1717749606.633 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-07T08:40:06.785Z,1717749606.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-07T08:40:06.785Z,1717749606.785 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-07T08:40:06.877Z,1717749606.877 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-07T08:40:06.967Z,1717749606.967 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-07T08:40:07.167Z,1717749607.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-07T08:40:07.167Z,1717749607.167 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-07T08:40:07.412Z,1717749607.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-07T08:40:07.414Z,1717749607.414 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2024-06-07T08:40:07.415Z,1717749607.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2024-06-07T08:40:07.509Z,1717749607.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2024-06-07T08:40:07.783Z,1717749607.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-07T08:40:07.783Z,1717749607.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2024-06-07T08:40:07.891Z,1717749607.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2024-06-07T08:40:08.067Z,1717749608.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2024-06-07T08:40:08.176Z,1717749608.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2024-06-07T08:40:08.298Z,1717749608.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2024-06-07T08:40:08.421Z,1717749608.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2024-06-07T08:40:08.538Z,1717749608.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2024-06-07T08:40:08.711Z,1717749608.711 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2024-06-07T08:40:08.818Z,1717749608.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2024-06-07T08:40:08.913Z,1717749608.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2024-06-07T08:40:09.021Z,1717749609.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2024-06-07T08:40:09.122Z,1717749609.122 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2024-06-07T08:40:09.122Z,1717749609.122 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-07T08:40:09.127Z,1717749609.127 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-07T08:40:09.299Z,1717749609.299 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-07T08:40:09.300Z,1717749609.300 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-07T08:40:09.457Z,1717749609.457 [BuoyancyServo] Loaded
2024-06-07T08:40:09.457Z,1717749609.457 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-07T08:40:09.480Z,1717749609.480 [ElevatorServo] Loaded
2024-06-07T08:40:09.481Z,1717749609.481 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-07T08:40:09.503Z,1717749609.503 [MassServo] Loaded
2024-06-07T08:40:09.503Z,1717749609.503 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-07T08:40:09.525Z,1717749609.525 [RudderServo] Loaded
2024-06-07T08:40:09.525Z,1717749609.525 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-07T08:40:09.542Z,1717749609.542 [ThrusterHE] Loaded
2024-06-07T08:40:09.542Z,1717749609.542 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-06-07T08:40:09.543Z,1717749609.543 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-07T08:40:09.543Z,1717749609.543 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-07T08:40:09.682Z,1717749609.682 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-07T08:40:09.697Z,1717749609.697 [SBIT] Loaded
2024-06-07T08:40:09.697Z,1717749609.697 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-07T08:40:09.700Z,1717749609.700 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-07T08:40:09.720Z,1717749609.720 [IBIT] Loaded
2024-06-07T08:40:09.721Z,1717749609.721 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-07T08:40:09.726Z,1717749609.726 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-07T08:40:09.946Z,1717749609.946 [CBIT] Loaded
2024-06-07T08:40:09.947Z,1717749609.947 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-07T08:40:09.947Z,1717749609.947 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-07T08:40:09.948Z,1717749609.948 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-07T08:40:10.118Z,1717749610.118 [CTD_Seabird] Loaded
2024-06-07T08:40:10.119Z,1717749610.119 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-07T08:40:10.120Z,1717749610.120 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2024-06-07T08:40:10.120Z,1717749610.120 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1104
2024-06-07T08:40:10.155Z,1717749610.155 [ESPComponent] Loaded
2024-06-07T08:40:10.155Z,1717749610.155 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-06-07T08:40:10.177Z,1717749610.177 [PAR_Licor] Loaded
2024-06-07T08:40:10.177Z,1717749610.177 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-07T08:40:10.220Z,1717749610.220 [WetLabsBB2FL] Loaded
2024-06-07T08:40:10.220Z,1717749610.220 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-07T08:40:10.221Z,1717749610.221 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2024-06-07T08:40:10.221Z,1717749610.221 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1105
2024-06-07T08:40:10.222Z,1717749610.222 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-07T08:40:10.222Z,1717749610.222 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-07T08:40:10.427Z,1717749610.427 [DepthRateCalculator] Loaded
2024-06-07T08:40:10.427Z,1717749610.427 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-07T08:40:10.433Z,1717749610.433 [PitchRateCalculator] Loaded
2024-06-07T08:40:10.433Z,1717749610.433 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-07T08:40:10.443Z,1717749610.443 [SpeedCalculator] Loaded
2024-06-07T08:40:10.443Z,1717749610.443 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-07T08:40:10.448Z,1717749610.448 [YawRateCalculator] Loaded
2024-06-07T08:40:10.448Z,1717749610.448 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-07T08:40:10.490Z,1717749610.490 [ElevatorOffsetCalculator] Loaded
2024-06-07T08:40:10.490Z,1717749610.490 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-07T08:40:10.490Z,1717749610.490 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-07T08:40:10.491Z,1717749610.491 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-07T08:40:10.785Z,1717749610.785 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-07T08:40:10.792Z,1717749610.792 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-07T08:40:10.852Z,1717749610.852 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-07T08:40:10.853Z,1717749610.853 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-07T08:40:11.003Z,1717749611.003 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-07T08:40:11.003Z,1717749611.003 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-07T08:40:11.017Z,1717749611.017 [NavChart] Loaded
2024-06-07T08:40:11.017Z,1717749611.017 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-07T08:40:11.022Z,1717749611.022 [UniversalFixResidualReporter] Loaded
2024-06-07T08:40:11.023Z,1717749611.023 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-07T08:40:11.023Z,1717749611.023 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-07T08:40:11.024Z,1717749611.024 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-07T08:40:11.459Z,1717749611.459 [AHRS_M2] Loaded
2024-06-07T08:40:11.459Z,1717749611.459 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-07T08:40:11.497Z,1717749611.497 [BackseatComponent] Loaded
2024-06-07T08:40:11.498Z,1717749611.498 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-06-07T08:40:11.499Z,1717749611.499 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A524E0
2024-06-07T08:40:11.499Z,1717749611.499 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1106
2024-06-07T08:40:11.502Z,1717749611.502 [LcmUniversalReporter] Loaded
2024-06-07T08:40:11.502Z,1717749611.502 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-06-07T08:40:12.238Z,1717749612.238 [BPC1] Loaded
2024-06-07T08:40:12.239Z,1717749612.239 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-07T08:40:12.314Z,1717749612.314 [DataOverHttps] Loaded
2024-06-07T08:40:12.314Z,1717749612.314 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-07T08:40:12.315Z,1717749612.315 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A824E0
2024-06-07T08:40:12.315Z,1717749612.315 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1107
2024-06-07T08:40:12.338Z,1717749612.338 [Depth_Keller] Loaded
2024-06-07T08:40:12.338Z,1717749612.338 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-07T08:40:12.343Z,1717749612.343 [DropWeight] Loaded
2024-06-07T08:40:12.344Z,1717749612.344 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-07T08:40:12.397Z,1717749612.397 [DVL_micro] Loaded
2024-06-07T08:40:12.397Z,1717749612.397 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-06-07T08:40:12.459Z,1717749612.459 [NAL9602] Loaded
2024-06-07T08:40:12.459Z,1717749612.459 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-07T08:40:12.485Z,1717749612.485 [Onboard] Loaded
2024-06-07T08:40:12.485Z,1717749612.485 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-07T08:40:12.486Z,1717749612.486 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AB24E0
2024-06-07T08:40:12.486Z,1717749612.486 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1108
2024-06-07T08:40:12.501Z,1717749612.501 [Power24vConverter] Loaded
2024-06-07T08:40:12.501Z,1717749612.501 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-07T08:40:12.516Z,1717749612.516 [Radio_Surface] Loaded
2024-06-07T08:40:12.516Z,1717749612.516 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-07T08:40:12.517Z,1717749612.517 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AE24E0
2024-06-07T08:40:12.518Z,1717749612.518 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1109
2024-06-07T08:40:12.533Z,1717749612.533 [Sonardyne_Nano] Loaded
2024-06-07T08:40:12.533Z,1717749612.533 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-06-07T08:40:12.630Z,1717749612.630 [DAT] Loaded
2024-06-07T08:40:12.630Z,1717749612.630 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-07T08:40:12.631Z,1717749612.631 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40B124E0
2024-06-07T08:40:12.631Z,1717749612.631 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1110
2024-06-07T08:40:12.632Z,1717749612.632 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-07T08:40:12.632Z,1717749612.632 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-07T08:40:12.687Z,1717749612.687 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-07T08:40:12.688Z,1717749612.688 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-07T08:40:12.740Z,1717749612.740 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-07T08:40:12.791Z,1717749612.791 [VerticalControl] Loaded
2024-06-07T08:40:12.792Z,1717749612.792 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-07T08:40:12.794Z,1717749612.794 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-07T08:40:12.838Z,1717749612.838 [HorizontalControl] Loaded
2024-06-07T08:40:12.838Z,1717749612.838 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-07T08:40:12.841Z,1717749612.841 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-07T08:40:12.842Z,1717749612.842 [SpeedControl] Loaded
2024-06-07T08:40:12.843Z,1717749612.843 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-07T08:40:12.845Z,1717749612.845 [LoopControl](DEBUG): Construct LoopControl.
2024-06-07T08:40:12.846Z,1717749612.846 [LoopControl] Loaded
2024-06-07T08:40:12.846Z,1717749612.846 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-07T08:40:12.847Z,1717749612.847 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-07T08:40:12.847Z,1717749612.847 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-07T08:40:12.857Z,1717749612.857 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-07T08:40:12.858Z,1717749612.858 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-07T08:40:13.196Z,1717749613.196 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-07T08:40:13.202Z,1717749613.202 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-07T08:40:13.205Z,1717749613.205 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-07T08:40:13.216Z,1717749613.216 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-07T08:40:13.217Z,1717749613.217 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-06-07T08:40:13.218Z,1717749613.218 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1111
2024-06-07T08:40:13.222Z,1717749613.222 [Supervisor](INFO): Main Thread ID is 826
2024-06-07T08:40:13.222Z,1717749613.222 [Supervisor](DEBUG): Running supervisor.
2024-06-07T08:40:13.223Z,1717749613.223 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1112
2024-06-07T08:40:13.224Z,1717749613.224 [CommandExec](INFO): Initializing the command executive.
2024-06-07T08:40:13.225Z,1717749613.225 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1113
2024-06-07T08:40:13.228Z,1717749613.228 [controlThread ThreadHandler](INFO): Handler Thread ID is 1114
2024-06-07T08:40:13.229Z,1717749613.229 [controlThread](DEBUG): Initializing ControlThread
2024-06-07T08:40:13.230Z,1717749613.230 [SBIT](INFO): Initialize SBIT Component.
2024-06-07T08:40:13.231Z,1717749613.231 [SBIT](IMPORTANT): git: 2024-06-04
2024-06-07T08:40:13.231Z,1717749613.231 [SBIT](INFO): git hash: 665aaa51948338ae26aa7901209e8336b196d4d1
2024-06-07T08:40:13.231Z,1717749613.231 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-07T08:40:13.233Z,1717749613.233 [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-06-07T08:40:13.234Z,1717749613.234 [SBIT](INFO): Beginning SBIT in 120.000000 seconds.
2024-06-07T08:40:13.234Z,1717749613.234 [IBIT](INFO): Initialize IBIT Component.
2024-06-07T08:40:13.235Z,1717749613.235 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-07T08:40:13.236Z,1717749613.236 [logger ThreadHandler](INFO): Handler Thread ID is 1115
2024-06-07T08:40:13.248Z,1717749613.248 [CBIT](DEBUG): Initialized mux pins.
2024-06-07T08:40:13.248Z,1717749613.248 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-07T08:40:13.256Z,1717749613.256 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1116
2024-06-07T08:40:13.257Z,1717749613.257 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-07T08:40:13.261Z,1717749613.261 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1118
2024-06-07T08:40:13.264Z,1717749613.264 [WetLabsBB2FL](INFO): Powering up
2024-06-07T08:40:13.265Z,1717749613.265 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1120
2024-06-07T08:40:13.272Z,1717749613.272 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-07T08:40:13.272Z,1717749613.272 [CBIT](DEBUG): Initializing heartbeat.
2024-06-07T08:40:13.273Z,1717749613.273 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1121
2024-06-07T08:40:13.275Z,1717749613.275 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-07T08:40:13.296Z,1717749613.296 [Onboard ThreadHandler](INFO): Handler Thread ID is 1122
2024-06-07T08:40:13.313Z,1717749613.313 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1123
2024-06-07T08:40:13.338Z,1717749613.338 [DAT ThreadHandler](INFO): Handler Thread ID is 1124
2024-06-07T08:40:13.339Z,1717749613.339 [DAT](INFO): Powering up
2024-06-07T08:40:13.339Z,1717749613.339 [DAT](DEBUG): Initializing DAT.
2024-06-07T08:40:13.344Z,1717749613.344 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-07T08:40:13.344Z,1717749613.344 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-07T08:40:13.352Z,1717749613.352 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1126
2024-06-07T08:40:13.355Z,1717749613.355 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-06-07T08:40:13.355Z,1717749613.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-06-07T08:40:13.355Z,1717749613.355 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-06-07T08:40:13.355Z,1717749613.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-06-07T08:40:13.355Z,1717749613.355 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-06-07T08:40:13.355Z,1717749613.355 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-06-07T08:40:13.388Z,1717749613.388 [CBIT](DEBUG): Backplane powered.
2024-06-07T08:40:13.392Z,1717749613.392 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-07T08:40:13.393Z,1717749613.393 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-07T08:40:13.393Z,1717749613.393 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-07T08:40:13.393Z,1717749613.393 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-07T08:40:13.393Z,1717749613.393 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-07T08:40:13.395Z,1717749613.395 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-07T08:40:13.395Z,1717749613.395 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-07T08:40:13.402Z,1717749613.402 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-07T08:40:13.404Z,1717749613.404 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-07T08:40:13.405Z,1717749613.405 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-07T08:40:13.405Z,1717749613.405 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-07T08:40:13.406Z,1717749613.406 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-07T08:40:13.407Z,1717749613.407 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-06-07T08:40:13.407Z,1717749613.407 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-06-07T08:40:13.412Z,1717749613.412 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-06-07T08:40:13.502Z,1717749613.502 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-06-07T08:40:13.522Z,1717749613.522 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-07T08:40:13.555Z,1717749613.555 [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-06-07T08:40:13.556Z,1717749613.556 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-06-07T08:40:13.556Z,1717749613.556 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-06-07T08:40:13.557Z,1717749613.557 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-06-07T08:40:13.720Z,1717749613.720 [Radio_Surface](INFO): Powering up
2024-06-07T08:40:13.875Z,1717749613.875 [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-06-07T08:40:13.883Z,1717749613.883 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-07T08:40:13.913Z,1717749613.913 [Default:A.Wait](DEBUG): Construct Wait.
2024-06-07T08:40:13.915Z,1717749613.915 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-07T08:40:13.939Z,1717749613.939 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-07T08:40:13.961Z,1717749613.961 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-07T08:40:13.967Z,1717749613.967 [Default:E.Execute](DEBUG): Construct Execute.
2024-06-07T08:40:13.986Z,1717749613.986 [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-06-07T08:40:13.990Z,1717749613.990 [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-06-07T08:40:14.015Z,1717749614.015 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-07T08:40:14.077Z,1717749614.077 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2024-06-07T08:40:14.085Z,1717749614.085 [Power24vConverter](INFO): Powering up.
2024-06-07T08:40:14.086Z,1717749614.086 [Sonardyne_Nano](INFO): Initializing.
2024-06-07T08:40:14.112Z,1717749614.112 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-06-07T08:40:14.138Z,1717749614.138 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-07T08:40:14.144Z,1717749614.144 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-07T08:40:14.145Z,1717749614.145 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-07T08:40:14.152Z,1717749614.152 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-07T08:40:14.153Z,1717749614.153 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-07T08:40:14.161Z,1717749614.161 [MassServo](DEBUG): Initializing MassServo.
2024-06-07T08:40:14.161Z,1717749614.161 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-07T08:40:14.168Z,1717749614.168 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-07T08:40:14.169Z,1717749614.169 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-06-07T08:40:14.176Z,1717749614.176 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-06-07T08:40:15.373Z,1717749615.373 [WetLabsBB2FL](INFO): Powering down
2024-06-07T08:40:16.022Z,1717749616.022 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-06-07T08:40:17.311Z,1717749617.311 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2407
2024-06-07T08:40:25.440Z,1717749625.440 [DAT](INFO): DAT read:
2024-06-07T08:40:25.442Z,1717749625.442 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-07T08:40:26.953Z,1717749626.953 [DAT](INFO): DAT read: MF Frequency Band
2024-06-07T08:40:26.957Z,1717749626.957 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-06-07T08:40:26.959Z,1717749626.959 [DAT](INFO): DAT read: Jun 7 2024 08:40:21
2024-06-07T08:40:28.213Z,1717749628.213 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-07T08:40:28.214Z,1717749628.214 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-06-07T08:40:28.214Z,1717749628.214 [DAT](INFO): commRate: 1200
2024-06-07T08:40:30.280Z,1717749630.280 [DAT](INFO): entering command mode
2024-06-07T08:40:30.480Z,1717749630.480 [DAT](INFO): DAT read:
2024-06-07T08:40:30.481Z,1717749630.481 [DAT](INFO): DAT read: user:1>
2024-06-07T08:40:30.481Z,1717749630.481 [DAT](INFO): setting verbose to 3
2024-06-07T08:40:30.732Z,1717749630.732 [DAT](INFO): DAT read: user:1>
2024-06-07T08:40:30.733Z,1717749630.733 [DAT](INFO): DAT read: Verbose | 3
2024-06-07T08:40:30.734Z,1717749630.734 [DAT](INFO): set verbose to 3
2024-06-07T08:40:30.734Z,1717749630.734 [DAT](INFO): setting DatVerbose to 27440
2024-06-07T08:40:30.984Z,1717749630.984 [DAT](INFO): DAT read: user:2>
2024-06-07T08:40:30.985Z,1717749630.985 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-07T08:40:30.986Z,1717749630.986 [DAT](INFO): set DatVerbose to 27440
2024-06-07T08:40:30.986Z,1717749630.986 [DAT](INFO): setting transmit power to 8
2024-06-07T08:40:31.236Z,1717749631.236 [DAT](INFO): DAT read: user:3>
2024-06-07T08:40:31.237Z,1717749631.237 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-07T08:40:31.238Z,1717749631.238 [DAT](INFO): set transmit power to 8
2024-06-07T08:40:31.238Z,1717749631.238 [DAT](INFO): setting local address to 5
2024-06-07T08:40:31.488Z,1717749631.488 [DAT](INFO): DAT read: user:4>
2024-06-07T08:40:31.489Z,1717749631.489 [DAT](INFO): DAT read: LocalAddr | 5
2024-06-07T08:40:31.490Z,1717749631.490 [DAT](INFO): set local address to 5
2024-06-07T08:40:31.491Z,1717749631.491 [DAT](INFO): Setting time to: 8:40:31 And date to:6/7/2024
2024-06-07T08:40:31.740Z,1717749631.740 [DAT](INFO): DAT read: user:5>
2024-06-07T08:40:31.741Z,1717749631.741 [DAT](INFO): DAT read: Fri Jun 7, 2024 08:40:31
2024-06-07T08:40:31.742Z,1717749631.742 [DAT](INFO): Local DAT time set to Fri Jun 7, 2024 08:40:31
2024-06-07T08:40:42.744Z,1717749642.744 [NAL9602](INFO): Powering up NAL9602
2024-06-07T08:40:53.652Z,1717749653.652 [NAL9602](INFO): NAL9602 initialized
2024-06-07T08:41:23.945Z,1717749683.945 [NAL9602](INFO): SBD MO Status=0, MOMSN=13000, MT Status=0, MTMSN=0
2024-06-07T08:41:23.945Z,1717749683.945 [NAL9602](INFO): No messages in MT queue
2024-06-07T08:41:34.045Z,1717749694.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084128.00,A,5600.07310,N,00807.43645,E,0.175,0.00,070624,,,A*64
2024-06-07T08:41:34.048Z,1717749694.048 [NAL9602](INFO): GPS fix at 20240607T084128: (56.001218, 8.123941)
2024-06-07T08:42:06.772Z,1717749726.772 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T08:42:13.657Z,1717749733.657 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-07T08:42:13.662Z,1717749733.662 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-07T08:42:24.623Z,1717749744.623 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.019288
CHAN A1 (24V): 0.049451
CHAN A2 (12V): -0.007577
CHAN A3 (5V): -0.027203
CHAN B0 (3.3V): -0.013243
CHAN B1 (3.15aV): -0.008809
CHAN B2 (3.15bV): -0.006185
CHAN B3 (GND): -0.014887
OPEN: 0.007122
Full Scale: +/- 1 mA
2024-06-07T08:43:06.570Z,1717749786.570 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004410
2024-06-07T08:43:07.386Z,1717749787.386 [SBIT](IMPORTANT): SBIT PASSED
2024-06-07T08:43:07.387Z,1717749787.387 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-07T08:43:07.388Z,1717749787.388 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-06-07T08:43:07.388Z,1717749787.388 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=150 cubic_centimeter;
2024-06-07T08:43:07.388Z,1717749787.388 [SBIT](IMPORTANT): VerticalControl.massDefault=2.5 centimeter;
2024-06-07T08:43:07.890Z,1717749787.890 [MissionManager](IMPORTANT): Started mission Startup
2024-06-07T08:43:07.890Z,1717749787.890 [Startup] Running Loop=1
2024-06-07T08:43:07.890Z,1717749787.890 [Startup](DEBUG): Aggregate::initialize Startup
2024-06-07T08:43:07.890Z,1717749787.890 [Startup:A.GoToSurface] Running Loop=1
2024-06-07T08:43:07.891Z,1717749787.891 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-07T08:43:07.891Z,1717749787.891 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-07T08:43:07.892Z,1717749787.892 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-07T08:43:07.892Z,1717749787.892 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-07T08:43:07.892Z,1717749787.892 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-07T08:43:07.893Z,1717749787.893 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-07T08:43:07.893Z,1717749787.893 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-07T08:43:07.895Z,1717749787.895 [Startup:StartupSatComms] Running Loop=1
2024-06-07T08:43:07.895Z,1717749787.895 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-06-07T08:43:07.895Z,1717749787.895 [Startup:StartupSatComms:A] Running Loop=1
2024-06-07T08:43:08.208Z,1717749788.208 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-06-07T08:43:09.820Z,1717749789.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084306.00,A,5600.07247,N,00807.43783,E,0.039,0.00,070624,,,D*6E
2024-06-07T08:43:09.822Z,1717749789.822 [NAL9602](INFO): GPS fix at 20240607T084306: (56.001208, 8.123964)
2024-06-07T08:43:09.832Z,1717749789.832 [Startup:StartupSatComms:A] Stopped
2024-06-07T08:43:09.832Z,1717749789.832 [Startup:StartupSatComms:B] Running Loop=1
2024-06-07T08:43:10.239Z,1717749790.239 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-07T08:43:18.219Z,1717749798.219 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240607T082456/Courier0010.lzma
2024-06-07T08:43:19.222Z,1717749799.222 [DataOverHttps](INFO): Moved sent file to Logs/20240607T082456/Courier0010.lzma.bak
2024-06-07T08:43:19.222Z,1717749799.222 [DataOverHttps](INFO): SBD MOMSN=21138398
2024-06-07T08:43:19.899Z,1717749799.899 [NAL9602](INFO): SBD MO Status=0, MOMSN=13001, MT Status=0, MTMSN=0
2024-06-07T08:43:19.899Z,1717749799.899 [NAL9602](INFO): No messages in MT queue
2024-06-07T08:43:20.747Z,1717749800.747 [DVL_micro](ERROR): No DVL communication! Re-initializing
2024-06-07T08:43:20.747Z,1717749800.747 [DVL_micro] Communications Fault, FailCount= 1
2024-06-07T08:43:20.747Z,1717749800.747 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:43:20.827Z,1717749800.827 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:43:21.204Z,1717749801.204 [DVL_micro](INFO): Powering down
2024-06-07T08:43:22.033Z,1717749802.033 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:43:22.033Z,1717749802.033 [DVL_micro] No Fault, FailCount= 1
2024-06-07T08:43:33.362Z,1717749813.362 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20240607T084002/Courier0000.lzma
2024-06-07T08:43:34.364Z,1717749814.364 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0000.lzma.bak
2024-06-07T08:43:34.364Z,1717749814.364 [DataOverHttps](INFO): SBD MOMSN=21138401
2024-06-07T08:43:39.714Z,1717749819.714 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-06-07T08:43:39.714Z,1717749819.714 [DVL_micro] Communications Fault, FailCount= 2
2024-06-07T08:43:39.714Z,1717749819.714 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:43:39.790Z,1717749819.790 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:43:40.191Z,1717749820.191 [DVL_micro](INFO): Powering down
2024-06-07T08:43:40.989Z,1717749820.989 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:43:40.989Z,1717749820.989 [DVL_micro] No Fault, FailCount= 2
2024-06-07T08:43:50.598Z,1717749830.598 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T08:43:56.486Z,1717749836.486 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20240607T082456/Express0011.lzma
2024-06-07T08:43:57.488Z,1717749837.488 [DataOverHttps](INFO): Moved sent file to Logs/20240607T082456/Express0011.lzma.bak
2024-06-07T08:43:57.488Z,1717749837.488 [DataOverHttps](INFO): SBD MOMSN=21138407
2024-06-07T08:43:58.800Z,1717749838.800 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-06-07T08:43:58.800Z,1717749838.800 [DVL_micro] Communications Fault, FailCount= 3
2024-06-07T08:43:58.800Z,1717749838.800 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:43:58.852Z,1717749838.852 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:43:58.853Z,1717749838.853 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-06-07T08:43:59.194Z,1717749839.194 [DVL_micro](INFO): Powering down
2024-06-07T08:43:59.216Z,1717749839.216 [CommandExec](FAULT): Scheduling is paused
2024-06-07T08:43:59.217Z,1717749839.217 [CBIT](INFO): Critical error at 20240607T084358
2024-06-07T08:43:59.217Z,1717749839.217 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-06-07T08:44:10.014Z,1717749850.014 [Startup:StartupSatComms:B](INFO): Timed out from 2024-06-07T08:43:09.8Z
2024-06-07T08:44:10.014Z,1717749850.014 [Startup:StartupSatComms:B] Stopped
2024-06-07T08:44:10.014Z,1717749850.014 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-06-07T08:44:10.014Z,1717749850.014 [Startup:StartupSatComms] Stopped
2024-06-07T08:44:10.014Z,1717749850.014 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-06-07T08:44:10.015Z,1717749850.015 [Startup](INFO): Completed Startup
2024-06-07T08:44:10.015Z,1717749850.015 [MissionManager](INFO): Startup is completed.
2024-06-07T08:44:10.015Z,1717749850.015 [MissionManager](INFO): Uninitializing Mission Startup
2024-06-07T08:44:10.015Z,1717749850.015 [Startup] Stopped
2024-06-07T08:44:10.016Z,1717749850.016 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-06-07T08:44:10.016Z,1717749850.016 [Startup:A.GoToSurface] Stopped
2024-06-07T08:44:10.016Z,1717749850.016 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-07T08:44:10.429Z,1717749850.429 [MissionManager](IMPORTANT): Started mission Default
2024-06-07T08:44:10.429Z,1717749850.429 [Default] Running Loop=1
2024-06-07T08:44:10.434Z,1717749850.434 [Default](DEBUG): Aggregate::initialize Default
2024-06-07T08:44:10.434Z,1717749850.434 [Default:B.GoToSurface] Running Loop=1
2024-06-07T08:44:10.434Z,1717749850.434 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-07T08:44:10.434Z,1717749850.434 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-07T08:44:10.435Z,1717749850.435 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-07T08:44:10.435Z,1717749850.435 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-07T08:44:10.435Z,1717749850.435 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-07T08:44:10.435Z,1717749850.435 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-07T08:44:10.436Z,1717749850.436 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-07T08:44:10.436Z,1717749850.436 [Default:A.Wait] Running Loop=1
2024-06-07T08:44:10.436Z,1717749850.436 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-06-07T08:44:15.975Z,1717749855.975 [DataOverHttps](INFO): Sending 849 bytes from file Logs/20240607T084002/Express0001.lzma
2024-06-07T08:44:17.980Z,1717749857.980 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0001.lzma.bak
2024-06-07T08:44:17.980Z,1717749857.980 [DataOverHttps](INFO): SBD MOMSN=21138421
2024-06-07T08:44:23.760Z,1717749863.760 [Default:A.Wait](INFO): Done Waiting.
2024-06-07T08:44:23.760Z,1717749863.760 [Default:A.Wait] Stopped
2024-06-07T08:44:23.760Z,1717749863.760 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T08:44:24.171Z,1717749864.171 [Default:CheckIn] Running Loop=1
2024-06-07T08:44:24.171Z,1717749864.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T08:44:24.171Z,1717749864.171 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T08:44:24.555Z,1717749864.555 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-07T08:44:26.163Z,1717749866.163 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084425.00,A,5600.07298,N,00807.43275,E,0.019,0.00,070624,,,D*64
2024-06-07T08:44:26.165Z,1717749866.165 [NAL9602](INFO): GPS fix at 20240607T084425: (56.001216, 8.123879)
2024-06-07T08:44:26.174Z,1717749866.174 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T08:44:26.175Z,1717749866.175 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T08:44:26.617Z,1717749866.617 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-06-07T08:44:37.041Z,1717749877.041 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240607T084002/Courier0004.lzma
2024-06-07T08:44:38.044Z,1717749878.044 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0004.lzma.bak
2024-06-07T08:44:38.044Z,1717749878.044 [DataOverHttps](INFO): SBD MOMSN=21138437
2024-06-07T08:44:59.118Z,1717749899.118 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240607T084002/Express0005.lzma
2024-06-07T08:45:00.120Z,1717749900.120 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0005.lzma.bak
2024-06-07T08:45:00.120Z,1717749900.120 [DataOverHttps](INFO): SBD MOMSN=21138441
2024-06-07T08:45:01.737Z,1717749901.737 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T08:45:01.737Z,1717749901.737 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T08:45:01.737Z,1717749901.737 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T08:45:04.152Z,1717749904.152 [NAL9602](INFO): SBD MO Status=0, MOMSN=13002, MT Status=0, MTMSN=0
2024-06-07T08:45:04.152Z,1717749904.152 [NAL9602](INFO): No messages in MT queue
2024-06-07T08:45:28.763Z,1717749928.763 [CommandExec](IMPORTANT): got command burn on
2024-06-07T08:45:28.763Z,1717749928.763 [CommandExec](IMPORTANT): Activating dropweight wire
2024-06-07T08:45:34.855Z,1717749934.855 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T08:45:41.863Z,1717749941.863 [CommandExec](IMPORTANT): got command burn off
2024-06-07T08:45:41.863Z,1717749941.863 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-06-07T08:46:20.223Z,1717749980.223 [CommandExec](IMPORTANT): got command ibit
2024-06-07T08:46:20.506Z,1717749980.506 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-06-07T08:46:20.506Z,1717749980.506 [IBIT](IMPORTANT): Beginning control surface checks.
2024-06-07T08:46:20.510Z,1717749980.510 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-07T08:46:22.109Z,1717749982.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084621.00,A,5600.07486,N,00807.43748,E,0.039,60.10,070624,,,D*55
2024-06-07T08:46:22.111Z,1717749982.111 [NAL9602](INFO): GPS fix at 20240607T084621: (56.001248, 8.123958)
2024-06-07T08:46:31.420Z,1717749991.420 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.031426
CHAN A1 (24V): 0.055289
CHAN A2 (12V): -0.011261
CHAN A3 (5V): -0.025199
CHAN B0 (3.3V): -0.015662
CHAN B1 (3.15aV): -0.012517
CHAN B2 (3.15bV): -0.006765
CHAN B3 (GND): -0.015320
OPEN: 0.007831
Full Scale: +/- 1 mA
2024-06-07T08:46:36.649Z,1717749996.649 [NAL9602](INFO): SBD MO Status=0, MOMSN=13003, MT Status=0, MTMSN=0
2024-06-07T08:46:36.649Z,1717749996.649 [NAL9602](INFO): No messages in MT queue
2024-06-07T08:46:37.861Z,1717749997.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084637.00,A,5600.07484,N,00807.43809,E,0.078,60.10,070624,,,D*5F
2024-06-07T08:46:37.871Z,1717749997.871 [NAL9602](INFO): GPS fix at 20240607T084637: (56.001247, 8.123968)
2024-06-07T08:46:40.717Z,1717750000.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084640.00,A,5600.07470,N,00807.43816,E,0.097,60.10,070624,,,D*5B
2024-06-07T08:46:40.719Z,1717750000.719 [NAL9602](INFO): GPS fix at 20240607T084640: (56.001245, 8.123969)
2024-06-07T08:46:43.925Z,1717750003.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084643.00,A,5600.07469,N,00807.43834,E,0.000,60.10,070624,,,D*5E
2024-06-07T08:46:43.927Z,1717750003.927 [NAL9602](INFO): GPS fix at 20240607T084643: (56.001245, 8.123972)
2024-06-07T08:46:46.748Z,1717750006.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084646.00,A,5600.07481,N,00807.43864,E,0.058,60.10,070624,,,D*55
2024-06-07T08:46:46.750Z,1717750006.750 [NAL9602](INFO): GPS fix at 20240607T084646: (56.001247, 8.123977)
2024-06-07T08:46:49.984Z,1717750009.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084649.00,A,5600.07492,N,00807.43869,E,0.058,60.10,070624,,,D*55
2024-06-07T08:46:49.987Z,1717750009.987 [NAL9602](INFO): GPS fix at 20240607T084649: (56.001249, 8.123978)
2024-06-07T08:46:52.812Z,1717750012.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084652.00,A,5600.07510,N,00807.43891,E,0.019,60.10,070624,,,D*56
2024-06-07T08:46:52.814Z,1717750012.814 [NAL9602](INFO): GPS fix at 20240607T084652: (56.001252, 8.123982)
2024-06-07T08:46:56.064Z,1717750016.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084655.00,A,5600.07517,N,00807.43896,E,0.019,60.10,070624,,,D*51
2024-06-07T08:46:56.066Z,1717750016.066 [NAL9602](INFO): GPS fix at 20240607T084655: (56.001253, 8.123983)
2024-06-07T08:46:58.877Z,1717750018.877 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084658.00,A,5600.07512,N,00807.43896,E,0.039,60.10,070624,,,D*5B
2024-06-07T08:46:58.880Z,1717750018.880 [NAL9602](INFO): GPS fix at 20240607T084658: (56.001252, 8.123983)
2024-06-07T08:46:58.898Z,1717750018.898 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:46:58.898Z,1717750018.898 [DVL_micro] No Fault, FailCount= 3
2024-06-07T08:47:01.697Z,1717750021.697 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084701.00,A,5600.07505,N,00807.43906,E,0.019,60.10,070624,,,D*5A
2024-06-07T08:47:01.699Z,1717750021.699 [NAL9602](INFO): GPS fix at 20240607T084701: (56.001251, 8.123984)
2024-06-07T08:47:04.932Z,1717750024.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084704.00,A,5600.07503,N,00807.43930,E,0.078,60.10,070624,,,D*5B
2024-06-07T08:47:04.934Z,1717750024.934 [NAL9602](INFO): GPS fix at 20240607T084704: (56.001250, 8.123988)
2024-06-07T08:47:07.025Z,1717750027.025 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 56.001251 Longitude: 8.123988
2024-06-07T08:47:07.429Z,1717750027.429 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2024-06-07T08:47:07.769Z,1717750027.769 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084707.00,A,5600.07486,N,00807.43947,E,0.078,60.10,070624,,,D*54
2024-06-07T08:47:07.771Z,1717750027.771 [NAL9602](INFO): GPS fix at 20240607T084707: (56.001248, 8.123991)
2024-06-07T08:47:07.824Z,1717750027.824 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-06-07T08:47:07.825Z,1717750027.825 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-06-07T08:47:07.825Z,1717750027.825 [IBIT](IMPORTANT): Pressure:9.207918 PSI
2024-06-07T08:47:07.825Z,1717750027.825 [IBIT](IMPORTANT): Humidity:0.238066 %
2024-06-07T08:47:08.336Z,1717750028.336 [IBIT](IMPORTANT): Vehicle Pitch:-0.180856 degrees
2024-06-07T08:47:08.336Z,1717750028.336 [IBIT](IMPORTANT): Vehicle Roll:-2.390188 degrees
2024-06-07T08:47:08.336Z,1717750028.336 [IBIT](IMPORTANT): Vehicle Heading:248.898819 degrees
2024-06-07T08:47:08.619Z,1717750028.619 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-06-07T08:47:08.619Z,1717750028.619 [IBIT](IMPORTANT): buoyancyNeutral: 150.000000 cc
2024-06-07T08:47:08.619Z,1717750028.619 [IBIT](IMPORTANT): massDefault: 2.500000 cm
2024-06-07T08:47:08.620Z,1717750028.620 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-06-07T08:47:08.620Z,1717750028.620 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-06-07T08:47:08.620Z,1717750028.620 [IBIT](IMPORTANT): IBIT PASSED
2024-06-07T08:47:16.642Z,1717750036.642 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-06-07T08:47:16.642Z,1717750036.642 [DVL_micro] Communications Fault, FailCount= 1
2024-06-07T08:47:16.642Z,1717750036.642 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:47:16.661Z,1717750036.661 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:47:17.142Z,1717750037.142 [DVL_micro](INFO): Powering down
2024-06-07T08:47:17.888Z,1717750037.888 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:47:17.888Z,1717750037.888 [DVL_micro] No Fault, FailCount= 1
2024-06-07T08:47:33.265Z,1717750053.265 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-06-07T08:47:33.267Z,1717750053.267 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2024-06-07T08:47:33.267Z,1717750053.267 [DVL_micro] Data Fault, FailCount= 2
2024-06-07T08:47:33.267Z,1717750053.267 [DVL_micro](ERROR): Data Fault
2024-06-07T08:47:33.332Z,1717750053.332 [CBIT](ERROR): Data Fault in component: DVL_micro
2024-06-07T08:47:33.726Z,1717750053.726 [DVL_micro](INFO): Powering down
2024-06-07T08:47:34.439Z,1717750054.439 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:47:34.439Z,1717750054.439 [DVL_micro] No Fault, FailCount= 2
2024-06-07T08:47:40.499Z,1717750060.499 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T08:47:48.165Z,1717750068.165 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2024-06-07T08:47:48.169Z,1717750068.169 [BPC1](INFO): Received data from all battery sticks.
2024-06-07T08:47:50.200Z,1717750070.200 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro
2024-06-07T08:50:02.302Z,1717750202.302 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-07T08:50:02.302Z,1717750202.302 [Default:CheckIn:C.Wait] Stopped
2024-06-07T08:50:02.303Z,1717750202.303 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T08:50:02.303Z,1717750202.303 [Default:CheckIn:D] Running Loop=1
2024-06-07T08:50:02.701Z,1717750202.701 [Default:CheckIn:D] Stopped
2024-06-07T08:50:02.701Z,1717750202.701 [Default:CheckIn:E] Running Loop=1
2024-06-07T08:50:03.117Z,1717750203.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.871187 min
2024-06-07T08:50:03.117Z,1717750203.117 [Default:CheckIn:E] Stopped
2024-06-07T08:50:03.117Z,1717750203.117 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-07T08:50:03.117Z,1717750203.117 [Default:CheckIn] Stopped
2024-06-07T08:50:03.117Z,1717750203.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T08:50:03.117Z,1717750203.117 [Default:CheckIn](INFO): Running loop #2
2024-06-07T08:50:03.118Z,1717750203.118 [Default:CheckIn] Running Loop=2
2024-06-07T08:50:03.118Z,1717750203.118 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T08:50:03.118Z,1717750203.118 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T08:50:05.115Z,1717750205.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,085004.00,A,5600.07433,N,00807.43408,E,0.019,60.10,070624,,,D*5E
2024-06-07T08:50:05.117Z,1717750205.117 [NAL9602](INFO): GPS fix at 20240607T085004: (56.001239, 8.123901)
2024-06-07T08:50:05.127Z,1717750205.127 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T08:50:05.127Z,1717750205.127 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T08:50:13.377Z,1717750213.377 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240607T084002/Courier0007.lzma
2024-06-07T08:50:14.380Z,1717750214.380 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0007.lzma.bak
2024-06-07T08:50:14.380Z,1717750214.380 [DataOverHttps](INFO): SBD MOMSN=21138485
2024-06-07T08:50:20.483Z,1717750220.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=13004, MT Status=0, MTMSN=0
2024-06-07T08:50:20.483Z,1717750220.483 [NAL9602](INFO): No messages in MT queue
2024-06-07T08:50:32.519Z,1717750232.519 [DataOverHttps](INFO): Sending 884 bytes from file Logs/20240607T084002/Express0008.lzma
2024-06-07T08:50:33.520Z,1717750233.520 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0008.lzma.bak
2024-06-07T08:50:33.520Z,1717750233.520 [DataOverHttps](INFO): SBD MOMSN=21138488
2024-06-07T08:50:35.041Z,1717750235.041 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T08:50:35.041Z,1717750235.041 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T08:50:35.041Z,1717750235.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T08:50:51.169Z,1717750251.169 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T08:51:48.167Z,1717750308.167 [CommandExec](IMPORTANT): got command gfscan
2024-06-07T08:51:48.175Z,1717750308.175 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-07T08:51:59.064Z,1717750319.064 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.032143
CHAN A1 (24V): 0.048550
CHAN A2 (12V): -0.007613
CHAN A3 (5V): -0.019910
CHAN B0 (3.3V): -0.014124
CHAN B1 (3.15aV): -0.010446
CHAN B2 (3.15bV): -0.007779
CHAN B3 (GND): -0.013075
OPEN: 0.007665
Full Scale: +/- 1 mA
2024-06-07T08:55:35.593Z,1717750535.593 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-07T08:55:35.593Z,1717750535.593 [Default:CheckIn:C.Wait] Stopped
2024-06-07T08:55:35.593Z,1717750535.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T08:55:35.593Z,1717750535.593 [Default:CheckIn:D] Running Loop=1
2024-06-07T08:55:36.008Z,1717750536.008 [Default:CheckIn:D] Stopped
2024-06-07T08:55:36.008Z,1717750536.008 [Default:CheckIn:E] Running Loop=1
2024-06-07T08:55:36.403Z,1717750536.403 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.426314 min
2024-06-07T08:55:36.403Z,1717750536.403 [Default:CheckIn:E] Stopped
2024-06-07T08:55:36.403Z,1717750536.403 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-07T08:55:36.403Z,1717750536.403 [Default:CheckIn] Stopped
2024-06-07T08:55:36.403Z,1717750536.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T08:55:36.403Z,1717750536.403 [Default:CheckIn](INFO): Running loop #3
2024-06-07T08:55:36.404Z,1717750536.404 [Default:CheckIn] Running Loop=3
2024-06-07T08:55:36.404Z,1717750536.404 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T08:55:36.404Z,1717750536.404 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T08:55:38.416Z,1717750538.416 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,085537.00,A,5600.07536,N,00807.43605,E,0.039,60.10,070624,,,D*52
2024-06-07T08:55:38.418Z,1717750538.418 [NAL9602](INFO): GPS fix at 20240607T085537: (56.001256, 8.123934)
2024-06-07T08:55:38.428Z,1717750538.428 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T08:55:38.428Z,1717750538.428 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T08:55:47.193Z,1717750547.193 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240607T084002/Courier0010.lzma
2024-06-07T08:55:48.196Z,1717750548.196 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0010.lzma.bak
2024-06-07T08:55:48.196Z,1717750548.196 [DataOverHttps](IMPORTANT): SBD MOMSN=21138549, MTMSN=20240607T085547
2024-06-07T08:55:48.512Z,1717750548.512 [NAL9602](INFO): SBD MO Status=0, MOMSN=13005, MT Status=0, MTMSN=0
2024-06-07T08:55:48.513Z,1717750548.513 [NAL9602](INFO): No messages in MT queue
2024-06-07T08:55:56.986Z,1717750556.986 [DataOverHttps](INFO): Received command: configset cbit.stopdepth 30 meter persist; configset cbit.abortdepth 40 meter persist;
2024-06-07T08:55:57.483Z,1717750557.483 [CommandExec](IMPORTANT): got command configSet CBIT.stopDepth 30 meter persist
2024-06-07T08:55:57.488Z,1717750557.488 [CommandExec](IMPORTANT): got command configSet CBIT.abortDepth 40 meter persist
2024-06-07T08:56:06.610Z,1717750566.610 [DataOverHttps](INFO): Sending 326 bytes from file Logs/20240607T084002/Express0011.lzma
2024-06-07T08:56:07.612Z,1717750567.612 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0011.lzma.bak
2024-06-07T08:56:07.612Z,1717750567.612 [DataOverHttps](INFO): SBD MOMSN=21138553
2024-06-07T08:56:19.214Z,1717750579.214 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T08:56:28.037Z,1717750588.037 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240607T084002/Courier0013.lzma
2024-06-07T08:56:29.040Z,1717750589.040 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0013.lzma.bak
2024-06-07T08:56:29.040Z,1717750589.040 [DataOverHttps](INFO): SBD MOMSN=21138560
2024-06-07T08:56:49.158Z,1717750609.158 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240607T084002/Express0014.lzma
2024-06-07T08:56:50.160Z,1717750610.160 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0014.lzma.bak
2024-06-07T08:56:50.160Z,1717750610.160 [DataOverHttps](INFO): SBD MOMSN=21138562
2024-06-07T08:56:51.561Z,1717750611.561 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T08:56:51.561Z,1717750611.561 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T08:56:51.561Z,1717750611.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T08:57:51.735Z,1717750671.735 [DVL_micro](ERROR): No DVL communication! Re-initializing
2024-06-07T08:57:51.735Z,1717750671.735 [DVL_micro] Communications Fault, FailCount= 1
2024-06-07T08:57:51.736Z,1717750671.736 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:57:51.755Z,1717750671.755 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:57:52.230Z,1717750672.230 [DVL_micro](INFO): Powering down
2024-06-07T08:57:52.962Z,1717750672.962 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:57:52.962Z,1717750672.962 [DVL_micro] No Fault, FailCount= 1
2024-06-07T08:58:10.721Z,1717750690.721 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-06-07T08:58:10.721Z,1717750690.721 [DVL_micro] Communications Fault, FailCount= 2
2024-06-07T08:58:10.721Z,1717750690.721 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:58:10.741Z,1717750690.741 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:58:11.218Z,1717750691.218 [DVL_micro](INFO): Powering down
2024-06-07T08:58:11.972Z,1717750691.972 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T08:58:11.972Z,1717750691.972 [DVL_micro] No Fault, FailCount= 2
2024-06-07T08:58:22.863Z,1717750702.863 [CommandExec](IMPORTANT): got command gfscan
2024-06-07T08:58:22.875Z,1717750702.875 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-07T08:58:29.711Z,1717750709.711 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-06-07T08:58:29.711Z,1717750709.711 [DVL_micro] Communications Fault, FailCount= 3
2024-06-07T08:58:29.711Z,1717750709.711 [DVL_micro](ERROR): Communications Fault
2024-06-07T08:58:29.730Z,1717750709.730 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-06-07T08:58:29.731Z,1717750709.731 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-06-07T08:58:30.206Z,1717750710.206 [DVL_micro](INFO): Powering down
2024-06-07T08:58:30.229Z,1717750710.229 [CBIT](INFO): Critical error at 20240607T085829
2024-06-07T08:58:33.770Z,1717750713.770 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007826
CHAN A1 (24V): -0.000006
CHAN A2 (12V): -0.005852
CHAN A3 (5V): -0.002622
CHAN B0 (3.3V): -0.001454
CHAN B1 (3.15aV): -0.000922
CHAN B2 (3.15bV): -0.001305
CHAN B3 (GND): 0.000058
OPEN: 0.006977
Full Scale: +/- 1 mA
2024-06-07T08:59:00.471Z,1717750740.471 [CommandExec](IMPORTANT): got command gfscan
2024-06-07T08:59:00.833Z,1717750740.833 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-07T08:59:11.776Z,1717750751.776 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.027724
CHAN A1 (24V): 0.047129
CHAN A2 (12V): -0.007576
CHAN A3 (5V): -0.018708
CHAN B0 (3.3V): -0.012628
CHAN B1 (3.15aV): -0.009562
CHAN B2 (3.15bV): -0.006888
CHAN B3 (GND): -0.012851
OPEN: 0.007530
Full Scale: +/- 1 mA
2024-06-07T08:59:20.671Z,1717750760.671 [CommandExec](IMPORTANT): got command gfscan
2024-06-07T08:59:21.037Z,1717750761.037 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-07T08:59:31.949Z,1717750771.949 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.025088
CHAN A1 (24V): 0.051507
CHAN A2 (12V): -0.009302
CHAN A3 (5V): -0.019949
CHAN B0 (3.3V): -0.013773
CHAN B1 (3.15aV): -0.010108
CHAN B2 (3.15bV): -0.007143
CHAN B3 (GND): -0.013263
OPEN: 0.006891
Full Scale: +/- 1 mA
2024-06-07T09:00:04.899Z,1717750804.899 [DataOverHttps](IMPORTANT): SBD MTMSN=20240607T090004
2024-06-07T09:00:13.250Z,1717750813.250 [DataOverHttps](INFO): Received command: get sonardyne_nano.chargepercentage
2024-06-07T09:00:14.501Z,1717750814.501 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2024-06-07T09:00:14.502Z,1717750814.502 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 99.000000 %
2024-06-07T09:01:29.909Z,1717750889.909 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T09:01:29.909Z,1717750889.909 [DVL_micro] No Fault, FailCount= 3
2024-06-07T09:01:45.252Z,1717750905.252 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2024-06-07T09:01:45.252Z,1717750905.252 [DVL_micro] Data Fault, FailCount= 1
2024-06-07T09:01:45.252Z,1717750905.252 [DVL_micro](ERROR): Data Fault
2024-06-07T09:01:45.271Z,1717750905.271 [CBIT](ERROR): Data Fault in component: DVL_micro
2024-06-07T09:01:45.738Z,1717750905.738 [DVL_micro](INFO): Powering down
2024-06-07T09:01:46.478Z,1717750906.478 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-06-07T09:01:46.478Z,1717750906.478 [DVL_micro] No Fault, FailCount= 1
2024-06-07T09:01:46.679Z,1717750906.679 [DataOverHttps](IMPORTANT): SBD MTMSN=20240607T090145
2024-06-07T09:01:52.134Z,1717750912.134 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-07T09:01:52.134Z,1717750912.134 [Default:CheckIn:C.Wait] Stopped
2024-06-07T09:01:52.134Z,1717750912.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T09:01:52.134Z,1717750912.134 [Default:CheckIn:D] Running Loop=1
2024-06-07T09:01:52.530Z,1717750912.530 [Default:CheckIn:D] Stopped
2024-06-07T09:01:52.530Z,1717750912.530 [Default:CheckIn:E] Running Loop=1
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.701666 min
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn:E] Stopped
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn] Stopped
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn](INFO): Running loop #4
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn] Running Loop=4
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T09:01:52.941Z,1717750912.941 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T09:01:54.946Z,1717750914.946 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090154.00,A,5600.07484,N,00807.43675,E,0.058,60.10,070624,,,D*5F
2024-06-07T09:01:54.948Z,1717750914.948 [NAL9602](INFO): GPS fix at 20240607T090154: (56.001247, 8.123946)
2024-06-07T09:01:54.958Z,1717750914.958 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T09:01:54.958Z,1717750914.958 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T09:01:55.226Z,1717750915.226 [DataOverHttps](INFO): Received command: get bpc1.platform_battery_voltage; get bpc1.platform_battery_charge
2024-06-07T09:01:58.087Z,1717750918.087 [CommandExec](IMPORTANT): got command get BPC1.platform_battery_voltage
2024-06-07T09:01:58.087Z,1717750918.087 [CommandExec](IMPORTANT): BPC1.platform_battery_voltage 16.367874 V
2024-06-07T09:01:58.088Z,1717750918.088 [CommandExec](IMPORTANT): got command get BPC1.platform_battery_charge
2024-06-07T09:01:58.088Z,1717750918.088 [CommandExec](IMPORTANT): BPC1.platform_battery_charge 344.057007 Ah
2024-06-07T09:02:02.312Z,1717750922.312 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro
2024-06-07T09:02:05.897Z,1717750925.897 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240607T084002/Courier0016.lzma
2024-06-07T09:02:06.900Z,1717750926.900 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0016.lzma.bak
2024-06-07T09:02:06.900Z,1717750926.900 [DataOverHttps](INFO): SBD MOMSN=21138597
2024-06-07T09:02:07.575Z,1717750927.575 [NAL9602](INFO): SBD MO Status=0, MOMSN=13006, MT Status=0, MTMSN=0
2024-06-07T09:02:07.575Z,1717750927.575 [NAL9602](INFO): No messages in MT queue
2024-06-07T09:02:26.890Z,1717750946.890 [DataOverHttps](INFO): Sending 611 bytes from file Logs/20240607T084002/Express0017.lzma
2024-06-07T09:02:27.892Z,1717750947.892 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0017.lzma.bak
2024-06-07T09:02:27.892Z,1717750947.892 [DataOverHttps](INFO): SBD MOMSN=21138601
2024-06-07T09:02:38.263Z,1717750958.263 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T09:02:48.069Z,1717750968.069 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240607T084002/Express0020.lzma
2024-06-07T09:02:49.072Z,1717750969.072 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0020.lzma.bak
2024-06-07T09:02:49.072Z,1717750969.072 [DataOverHttps](INFO): SBD MOMSN=21138621
2024-06-07T09:02:50.801Z,1717750970.801 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T09:02:50.801Z,1717750970.801 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T09:02:50.801Z,1717750970.801 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T09:03:53.010Z,1717751033.010 [DVL_micro](ERROR): Failed to parse:
:WI,+02442,+00571,-02304,+00000,A
2024-06-07T09:07:35.623Z,1717751255.623 [DataOverHttps](IMPORTANT): SBD MTMSN=20240607T090735
2024-06-07T09:07:44.153Z,1717751264.153 [DataOverHttps](INFO): Received command: strobe off
2024-06-07T09:07:44.215Z,1717751264.215 [CommandExec](IMPORTANT): got command strobe off
2024-06-07T09:07:44.215Z,1717751264.215 [CommandExec](IMPORTANT): Deactivating strobe
2024-06-07T09:07:51.399Z,1717751271.399 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-07T09:07:51.399Z,1717751271.399 [Default:CheckIn:C.Wait] Stopped
2024-06-07T09:07:51.399Z,1717751271.399 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T09:07:51.399Z,1717751271.399 [Default:CheckIn:D] Running Loop=1
2024-06-07T09:07:51.795Z,1717751271.795 [Default:CheckIn:D] Stopped
2024-06-07T09:07:51.795Z,1717751271.795 [Default:CheckIn:E] Running Loop=1
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.689427 min
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn:E] Stopped
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn] Stopped
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn](INFO): Running loop #5
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn] Running Loop=5
2024-06-07T09:07:52.200Z,1717751272.200 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T09:07:52.201Z,1717751272.201 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T09:07:54.215Z,1717751274.215 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090753.00,A,5600.07288,N,00807.43496,E,0.039,60.10,070624,,,D*5C
2024-06-07T09:07:54.217Z,1717751274.217 [NAL9602](INFO): GPS fix at 20240607T090753: (56.001215, 8.123916)
2024-06-07T09:07:54.227Z,1717751274.227 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T09:07:54.227Z,1717751274.227 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T09:08:03.784Z,1717751283.784 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240607T084002/Courier0022.lzma
2024-06-07T09:08:04.784Z,1717751284.784 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0022.lzma.bak
2024-06-07T09:08:04.784Z,1717751284.784 [DataOverHttps](INFO): SBD MOMSN=21138676
2024-06-07T09:08:07.949Z,1717751287.949 [NAL9602](INFO): SBD MO Status=0, MOMSN=13007, MT Status=0, MTMSN=0
2024-06-07T09:08:07.949Z,1717751287.949 [NAL9602](INFO): No messages in MT queue
2024-06-07T09:08:24.848Z,1717751304.848 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20240607T084002/Express0023.lzma
2024-06-07T09:08:25.848Z,1717751305.848 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0023.lzma.bak
2024-06-07T09:08:25.848Z,1717751305.848 [DataOverHttps](INFO): SBD MOMSN=21138682
2024-06-07T09:08:38.652Z,1717751318.652 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T09:08:46.067Z,1717751326.067 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240607T084002/Express0026.lzma
2024-06-07T09:08:47.068Z,1717751327.068 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0026.lzma.bak
2024-06-07T09:08:47.068Z,1717751327.068 [DataOverHttps](INFO): SBD MOMSN=21138693
2024-06-07T09:08:48.791Z,1717751328.791 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T09:08:48.791Z,1717751328.791 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T09:08:48.791Z,1717751328.791 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T09:13:49.351Z,1717751629.351 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-07T09:13:49.352Z,1717751629.352 [Default:CheckIn:C.Wait] Stopped
2024-06-07T09:13:49.352Z,1717751629.352 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T09:13:49.352Z,1717751629.352 [Default:CheckIn:D] Running Loop=1
2024-06-07T09:13:49.765Z,1717751629.765 [Default:CheckIn:D] Stopped
2024-06-07T09:13:49.765Z,1717751629.765 [Default:CheckIn:E] Running Loop=1
2024-06-07T09:13:50.160Z,1717751630.160 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.655595 min
2024-06-07T09:13:50.160Z,1717751630.160 [Default:CheckIn:E] Stopped
2024-06-07T09:13:50.160Z,1717751630.160 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-07T09:13:50.160Z,1717751630.160 [Default:CheckIn] Stopped
2024-06-07T09:13:50.160Z,1717751630.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T09:13:50.161Z,1717751630.161 [Default:CheckIn](INFO): Running loop #6
2024-06-07T09:13:50.161Z,1717751630.161 [Default:CheckIn] Running Loop=6
2024-06-07T09:13:50.161Z,1717751630.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T09:13:50.161Z,1717751630.161 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T09:13:52.173Z,1717751632.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091351.00,A,5600.07441,N,00807.43509,E,0.078,60.10,070624,,,D*5A
2024-06-07T09:13:52.179Z,1717751632.179 [NAL9602](INFO): GPS fix at 20240607T091351: (56.001240, 8.123918)
2024-06-07T09:13:52.190Z,1717751632.190 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T09:13:52.191Z,1717751632.191 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T09:14:01.037Z,1717751641.037 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20240607T084002/Courier0028.lzma
2024-06-07T09:14:02.040Z,1717751642.040 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0028.lzma.bak
2024-06-07T09:14:02.040Z,1717751642.040 [DataOverHttps](INFO): SBD MOMSN=21138707
2024-06-07T09:14:05.535Z,1717751645.535 [NAL9602](INFO): SBD MO Status=0, MOMSN=13008, MT Status=0, MTMSN=0
2024-06-07T09:14:05.536Z,1717751645.536 [NAL9602](INFO): No messages in MT queue
2024-06-07T09:14:21.089Z,1717751661.089 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240607T084002/Express0029.lzma
2024-06-07T09:14:22.092Z,1717751662.092 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0029.lzma.bak
2024-06-07T09:14:22.092Z,1717751662.092 [DataOverHttps](INFO): SBD MOMSN=21138716
2024-06-07T09:14:23.700Z,1717751663.700 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T09:14:23.701Z,1717751663.701 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T09:14:23.701Z,1717751663.701 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T09:14:36.215Z,1717751676.215 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T09:19:24.289Z,1717751964.289 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-06-07T09:19:24.289Z,1717751964.289 [Default:CheckIn:C.Wait] Stopped
2024-06-07T09:19:24.289Z,1717751964.289 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T09:19:24.289Z,1717751964.289 [Default:CheckIn:D] Running Loop=1
2024-06-07T09:19:24.675Z,1717751964.675 [Default:CheckIn:D] Stopped
2024-06-07T09:19:24.675Z,1717751964.675 [Default:CheckIn:E] Running Loop=1
2024-06-07T09:19:25.094Z,1717751965.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.237423 min
2024-06-07T09:19:25.094Z,1717751965.094 [Default:CheckIn:E] Stopped
2024-06-07T09:19:25.094Z,1717751965.094 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-06-07T09:19:25.094Z,1717751965.094 [Default:CheckIn] Stopped
2024-06-07T09:19:25.094Z,1717751965.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T09:19:25.094Z,1717751965.094 [Default:CheckIn](INFO): Running loop #7
2024-06-07T09:19:25.095Z,1717751965.095 [Default:CheckIn] Running Loop=7
2024-06-07T09:19:25.095Z,1717751965.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-06-07T09:19:25.095Z,1717751965.095 [Default:CheckIn:Read_GPS] Running Loop=1
2024-06-07T09:19:27.092Z,1717751967.092 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091926.00,A,5600.07364,N,00807.43446,E,0.039,60.10,070624,,,D*5F
2024-06-07T09:19:27.094Z,1717751967.094 [NAL9602](INFO): GPS fix at 20240607T091926: (56.001227, 8.123908)
2024-06-07T09:19:27.104Z,1717751967.104 [Default:CheckIn:Read_GPS] Stopped
2024-06-07T09:19:27.104Z,1717751967.104 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-06-07T09:19:36.277Z,1717751976.277 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20240607T084002/Courier0031.lzma
2024-06-07T09:19:37.280Z,1717751977.280 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Courier0031.lzma.bak
2024-06-07T09:19:37.280Z,1717751977.280 [DataOverHttps](INFO): SBD MOMSN=21138744
2024-06-07T09:19:50.927Z,1717751990.927 [NAL9602](INFO): SBD MO Status=0, MOMSN=13009, MT Status=0, MTMSN=0
2024-06-07T09:19:50.927Z,1717751990.927 [NAL9602](INFO): No messages in MT queue
2024-06-07T09:19:57.593Z,1717751997.593 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240607T084002/Express0032.lzma
2024-06-07T09:19:58.596Z,1717751998.596 [DataOverHttps](INFO): Moved sent file to Logs/20240607T084002/Express0032.lzma.bak
2024-06-07T09:19:58.596Z,1717751998.596 [DataOverHttps](INFO): SBD MOMSN=21138747
2024-06-07T09:20:00.233Z,1717752000.233 [Default:CheckIn:Read_Iridium] Stopped
2024-06-07T09:20:00.233Z,1717752000.233 [Default:CheckIn:C.Wait] Running Loop=1
2024-06-07T09:20:00.233Z,1717752000.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-06-07T09:20:21.636Z,1717752021.636 [NAL9602](INFO): Not Powering down - fast GPS
2024-06-07T09:23:44.739Z,1717752224.739 [DataOverHttps](IMPORTANT): SBD MTMSN=20240607T092344
2024-06-07T09:23:53.242Z,1717752233.242 [DataOverHttps](INFO): Received command: restart app
2024-06-07T09:23:53.243Z,1717752233.243 [CommandExec](IMPORTANT): got command restart application
2024-06-07T09:23:54.246Z,1717752234.246 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:54.246Z,1717752234.246 [CommandExec](INFO): Uninitializing the command executive.
2024-06-07T09:23:54.246Z,1717752234.246 [CommandExec](INFO): Uninitializing the command scheduler.
2024-06-07T09:23:54.246Z,1717752234.246 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:54.418Z,1717752234.418 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-06-07T09:23:54.418Z,1717752234.418 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-06-07T09:23:54.418Z,1717752234.418 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:54.419Z,1717752234.419 [NavChartDb](INFO): Join timeout helper Thread ID is 1313
2024-06-07T09:23:54.686Z,1717752234.686 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:54.686Z,1717752234.686 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:54.702Z,1717752234.702 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-06-07T09:23:54.702Z,1717752234.702 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:54.703Z,1717752234.703 [DAT](INFO): Join timeout helper Thread ID is 1314
2024-06-07T09:23:54.914Z,1717752234.914 [DAT](INFO): Powering down
2024-06-07T09:23:54.987Z,1717752234.987 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:54.987Z,1717752234.987 [DAT](INFO): Powering down
2024-06-07T09:23:54.988Z,1717752234.988 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:54.998Z,1717752234.998 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-06-07T09:23:54.998Z,1717752234.998 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:54.998Z,1717752234.998 [Radio_Surface](INFO): Join timeout helper Thread ID is 1315
2024-06-07T09:23:55.054Z,1717752235.054 [Radio_Surface](INFO): Powering down
2024-06-07T09:23:55.055Z,1717752235.055 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:55.055Z,1717752235.055 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:55.067Z,1717752235.067 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-06-07T09:23:55.067Z,1717752235.067 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:55.068Z,1717752235.068 [Onboard](INFO): Join timeout helper Thread ID is 1316
2024-06-07T09:23:56.250Z,1717752236.250 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-06-07T09:23:58.102Z,1717752238.102 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.102Z,1717752238.102 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.114Z,1717752238.114 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-06-07T09:23:58.114Z,1717752238.114 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.114Z,1717752238.114 [DataOverHttps](INFO): Join timeout helper Thread ID is 1317
2024-06-07T09:23:58.258Z,1717752238.258 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.258Z,1717752238.258 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.277Z,1717752238.277 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-06-07T09:23:58.277Z,1717752238.277 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.277Z,1717752238.277 [BackseatComponent](INFO): Join timeout helper Thread ID is 1318
2024-06-07T09:23:58.294Z,1717752238.294 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.294Z,1717752238.294 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.296Z,1717752238.296 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-06-07T09:23:58.296Z,1717752238.296 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.296Z,1717752238.296 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1319
2024-06-07T09:23:58.438Z,1717752238.438 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.438Z,1717752238.438 [WetLabsBB2FL](INFO): Powering down
2024-06-07T09:23:58.439Z,1717752238.439 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.449Z,1717752238.449 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-06-07T09:23:58.449Z,1717752238.449 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.467Z,1717752238.467 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1320
2024-06-07T09:23:58.834Z,1717752238.834 [CTD_Seabird](INFO): Powering down
2024-06-07T09:23:58.846Z,1717752238.846 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.846Z,1717752238.846 [CTD_Seabird](INFO): Powering down
2024-06-07T09:23:58.858Z,1717752238.858 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.875Z,1717752238.875 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-06-07T09:23:58.875Z,1717752238.875 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.875Z,1717752238.875 [logger](INFO): Join timeout helper Thread ID is 1321
2024-06-07T09:23:58.892Z,1717752238.892 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.893Z,1717752238.893 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.906Z,1717752238.906 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-06-07T09:23:58.907Z,1717752238.907 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.907Z,1717752238.907 [CommandLine](INFO): Join timeout helper Thread ID is 1322
2024-06-07T09:23:58.922Z,1717752238.922 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.922Z,1717752238.922 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.926Z,1717752238.926 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-06-07T09:23:58.927Z,1717752238.927 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.927Z,1717752238.927 [CommandExec](INFO): Join timeout helper Thread ID is 1323
2024-06-07T09:23:58.928Z,1717752238.928 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-06-07T09:23:58.928Z,1717752238.928 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:58.929Z,1717752238.929 [controlThread](INFO): Join timeout helper Thread ID is 1324
2024-06-07T09:23:58.962Z,1717752238.962 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-07T09:23:58.962Z,1717752238.962 [controlThread](DEBUG): Uninitializing ControlThread
2024-06-07T09:23:58.964Z,1717752238.964 [AHRS_M2](INFO): Powering down
2024-06-07T09:23:59.130Z,1717752239.130 [DVL_micro](INFO): Powering down
2024-06-07T09:23:59.131Z,1717752239.131 [NAL9602](INFO): Powering down
2024-06-07T09:23:59.132Z,1717752239.132 [Sonardyne_Nano](INFO): Powering down
2024-06-07T09:23:59.250Z,1717752239.250 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-06-07T09:23:59.251Z,1717752239.251 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-06-07T09:23:59.252Z,1717752239.252 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-06-07T09:23:59.252Z,1717752239.252 [MissionManager](INFO): Uninitializing Mission Default
2024-06-07T09:23:59.252Z,1717752239.252 [Default] Stopped
2024-06-07T09:23:59.252Z,1717752239.252 [Default](DEBUG): Aggregate::uninitialize Default
2024-06-07T09:23:59.252Z,1717752239.252 [Default:B.GoToSurface] Stopped
2024-06-07T09:23:59.252Z,1717752239.252 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-07T09:23:59.253Z,1717752239.253 [Default:CheckIn] Stopped
2024-06-07T09:23:59.253Z,1717752239.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-06-07T09:23:59.253Z,1717752239.253 [Default:CheckIn:C.Wait] Stopped
2024-06-07T09:23:59.253Z,1717752239.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-06-07T09:23:59.256Z,1717752239.256 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-06-07T09:23:59.256Z,1717752239.256 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-06-07T09:23:59.256Z,1717752239.256 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-06-07T09:23:59.256Z,1717752239.256 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-06-07T09:23:59.257Z,1717752239.257 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-06-07T09:23:59.257Z,1717752239.257 [BuoyancyServo](INFO): Powering down
2024-06-07T09:23:59.270Z,1717752239.270 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-07T09:23:59.270Z,1717752239.270 [ElevatorServo](INFO): Powering down
2024-06-07T09:23:59.271Z,1717752239.271 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-06-07T09:23:59.271Z,1717752239.271 [MassServo](INFO): Powering down
2024-06-07T09:23:59.272Z,1717752239.272 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-06-07T09:23:59.272Z,1717752239.272 [RudderServo](INFO): Powering down
2024-06-07T09:23:59.273Z,1717752239.273 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-06-07T09:23:59.273Z,1717752239.273 [ThrusterHE](INFO): Powering down
2024-06-07T09:23:59.273Z,1717752239.273 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-06-07T09:23:59.274Z,1717752239.274 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-06-07T09:23:59.274Z,1717752239.274 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-06-07T09:23:59.274Z,1717752239.274 [CBIT](DEBUG): Powering off loads.
2024-06-07T09:23:59.286Z,1717752239.286 [CBIT](DEBUG): Disabling WDT.
2024-06-07T09:23:59.298Z,1717752239.298 [CBIT](DEBUG): Opening all GF detection circuits.
2024-06-07T09:23:59.299Z,1717752239.299 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.337Z,1717752239.337 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.344Z,1717752239.344 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.374Z,1717752239.374 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.379Z,1717752239.379 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.381Z,1717752239.381 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.389Z,1717752239.389 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.432Z,1717752239.432 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.484Z,1717752239.484 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-07T09:23:59.550Z,1717752239.550 [logger ThreadHandler](INFO): Thread cancelled.