2023-04-28T16:54:59.094Z,1682700899.094 [Supervisor](DEBUG): Initializing supervisor.
2023-04-28T16:54:59.099Z,1682700899.099 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-04-28T16:54:59.100Z,1682700899.100 [SyncHandler](INFO): Protected caller Thread ID is 1014
2023-04-28T16:54:59.101Z,1682700899.101 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-04-28T16:54:59.102Z,1682700899.102 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-04-28T16:54:59.102Z,1682700899.102 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1015
2023-04-28T16:54:59.106Z,1682700899.106 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-04-28T16:54:59.124Z,1682700899.124 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-04-28T16:54:59.125Z,1682700899.125 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-04-28T16:54:59.126Z,1682700899.126 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1016
2023-04-28T16:54:59.130Z,1682700899.130 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-04-28T16:54:59.131Z,1682700899.131 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-04-28T16:54:59.131Z,1682700899.131 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1017
2023-04-28T16:54:59.133Z,1682700899.133 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-04-28T16:54:59.134Z,1682700899.134 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-04-28T16:54:59.135Z,1682700899.135 [logger ThreadHandler](INFO): Protected caller Thread ID is 1018
2023-04-28T16:54:59.138Z,1682700899.138 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-04-28T16:54:59.139Z,1682700899.139 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-04-28T16:54:59.140Z,1682700899.140 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-04-28T16:54:59.410Z,1682700899.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-04-28T16:54:59.411Z,1682700899.411 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-04-28T16:54:59.491Z,1682700899.491 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-04-28T16:54:59.965Z,1682700899.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-04-28T16:54:59.966Z,1682700899.966 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-04-28T16:55:00.291Z,1682700900.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-04-28T16:55:00.292Z,1682700900.292 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-04-28T16:55:00.387Z,1682700900.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-04-28T16:55:00.388Z,1682700900.388 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-04-28T16:55:00.780Z,1682700900.780 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-04-28T16:55:00.781Z,1682700900.781 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-04-28T16:55:00.991Z,1682700900.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-04-28T16:55:00.991Z,1682700900.991 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-04-28T16:55:01.497Z,1682700901.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-04-28T16:55:01.498Z,1682700901.498 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-04-28T16:55:01.636Z,1682700901.636 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-04-28T16:55:01.637Z,1682700901.637 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-04-28T16:55:01.715Z,1682700901.715 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-04-28T16:55:02.462Z,1682700902.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-04-28T16:55:02.463Z,1682700902.463 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-04-28T16:55:02.828Z,1682700902.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-04-28T16:55:02.829Z,1682700902.829 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-04-28T16:55:03.019Z,1682700903.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-04-28T16:55:03.019Z,1682700903.019 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-04-28T16:55:03.130Z,1682700903.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-04-28T16:55:03.130Z,1682700903.130 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-04-28T16:55:03.353Z,1682700903.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-04-28T16:55:03.353Z,1682700903.353 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-04-28T16:55:03.624Z,1682700903.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-04-28T16:55:03.626Z,1682700903.626 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-04-28T16:55:03.627Z,1682700903.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-04-28T16:55:03.908Z,1682700903.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-04-28T16:55:04.015Z,1682700904.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-04-28T16:55:04.228Z,1682700904.228 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-04-28T16:55:04.355Z,1682700904.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-04-28T16:55:04.561Z,1682700904.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-04-28T16:55:04.673Z,1682700904.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-04-28T16:55:04.959Z,1682700904.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-04-28T16:55:05.035Z,1682700905.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-04-28T16:55:05.181Z,1682700905.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-04-28T16:55:05.318Z,1682700905.318 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-04-28T16:55:05.426Z,1682700905.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-04-28T16:55:05.690Z,1682700905.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-04-28T16:55:05.692Z,1682700905.692 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-04-28T16:55:05.692Z,1682700905.692 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-04-28T16:55:05.712Z,1682700905.712 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-04-28T16:55:05.762Z,1682700905.762 [VerticalControl](DEBUG): Construct VerticalControl.
2023-04-28T16:55:05.823Z,1682700905.823 [VerticalControl] Loaded
2023-04-28T16:55:05.824Z,1682700905.824 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-04-28T16:55:05.827Z,1682700905.827 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-04-28T16:55:05.869Z,1682700905.869 [HorizontalControl] Loaded
2023-04-28T16:55:05.869Z,1682700905.869 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-04-28T16:55:05.872Z,1682700905.872 [SpeedControl](DEBUG): Construct SpeedControl.
2023-04-28T16:55:05.875Z,1682700905.875 [SpeedControl] Loaded
2023-04-28T16:55:05.875Z,1682700905.875 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-04-28T16:55:05.878Z,1682700905.878 [LoopControl](DEBUG): Construct LoopControl.
2023-04-28T16:55:05.878Z,1682700905.878 [LoopControl] Loaded
2023-04-28T16:55:05.878Z,1682700905.878 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-04-28T16:55:05.879Z,1682700905.879 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-04-28T16:55:05.879Z,1682700905.879 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-04-28T16:55:05.889Z,1682700905.889 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-04-28T16:55:05.890Z,1682700905.890 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-04-28T16:55:06.039Z,1682700906.039 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-04-28T16:55:06.039Z,1682700906.039 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-04-28T16:55:06.191Z,1682700906.191 [BuoyancyServo] Loaded
2023-04-28T16:55:06.191Z,1682700906.191 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-04-28T16:55:06.211Z,1682700906.211 [ElevatorServo] Loaded
2023-04-28T16:55:06.211Z,1682700906.211 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-04-28T16:55:06.231Z,1682700906.231 [MassServo] Loaded
2023-04-28T16:55:06.231Z,1682700906.231 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-04-28T16:55:06.249Z,1682700906.249 [RudderServo] Loaded
2023-04-28T16:55:06.250Z,1682700906.250 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-04-28T16:55:06.267Z,1682700906.267 [ThrusterServo] Loaded
2023-04-28T16:55:06.268Z,1682700906.268 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-04-28T16:55:06.268Z,1682700906.268 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-04-28T16:55:06.269Z,1682700906.269 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-04-28T16:55:06.381Z,1682700906.381 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-04-28T16:55:06.381Z,1682700906.381 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-04-28T16:55:06.395Z,1682700906.395 [NavChart] Loaded
2023-04-28T16:55:06.396Z,1682700906.396 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-04-28T16:55:06.401Z,1682700906.401 [UniversalFixResidualReporter] Loaded
2023-04-28T16:55:06.402Z,1682700906.402 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-04-28T16:55:06.402Z,1682700906.402 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-04-28T16:55:06.403Z,1682700906.403 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-04-28T16:55:06.458Z,1682700906.458 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-04-28T16:55:06.459Z,1682700906.459 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-04-28T16:55:06.878Z,1682700906.878 [AHRS_M2] Loaded
2023-04-28T16:55:06.879Z,1682700906.879 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-04-28T16:55:07.692Z,1682700907.692 [BPC1] Loaded
2023-04-28T16:55:07.692Z,1682700907.692 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-04-28T16:55:07.766Z,1682700907.766 [DataOverHttps] Loaded
2023-04-28T16:55:07.767Z,1682700907.767 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-04-28T16:55:07.768Z,1682700907.768 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408FD4E0
2023-04-28T16:55:07.768Z,1682700907.768 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1103
2023-04-28T16:55:07.789Z,1682700907.789 [Depth_Keller] Loaded
2023-04-28T16:55:07.789Z,1682700907.789 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-04-28T16:55:07.794Z,1682700907.794 [DropWeight] Loaded
2023-04-28T16:55:07.795Z,1682700907.795 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-04-28T16:55:07.856Z,1682700907.856 [NAL9602] Loaded
2023-04-28T16:55:07.857Z,1682700907.857 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-04-28T16:55:07.886Z,1682700907.886 [Onboard] Loaded
2023-04-28T16:55:07.886Z,1682700907.886 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-04-28T16:55:07.887Z,1682700907.887 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4092D4E0
2023-04-28T16:55:07.888Z,1682700907.888 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1104
2023-04-28T16:55:07.901Z,1682700907.901 [Power24vConverter] Loaded
2023-04-28T16:55:07.901Z,1682700907.901 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-04-28T16:55:07.915Z,1682700907.915 [Radio_Surface] Loaded
2023-04-28T16:55:07.915Z,1682700907.915 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-04-28T16:55:07.916Z,1682700907.916 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4095D4E0
2023-04-28T16:55:07.916Z,1682700907.916 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1105
2023-04-28T16:55:07.917Z,1682700907.917 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-04-28T16:55:07.918Z,1682700907.918 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-04-28T16:55:07.969Z,1682700907.969 [DepthRateCalculator] Loaded
2023-04-28T16:55:07.970Z,1682700907.970 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-04-28T16:55:07.975Z,1682700907.975 [PitchRateCalculator] Loaded
2023-04-28T16:55:07.975Z,1682700907.975 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-04-28T16:55:07.984Z,1682700907.984 [SpeedCalculator] Loaded
2023-04-28T16:55:07.984Z,1682700907.984 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-04-28T16:55:08.000Z,1682700908.000 [VerticalHomogeneityIndexCalculator] Loaded
2023-04-28T16:55:08.000Z,1682700908.000 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-04-28T16:55:08.005Z,1682700908.005 [YawRateCalculator] Loaded
2023-04-28T16:55:08.005Z,1682700908.005 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-04-28T16:55:08.024Z,1682700908.024 [ElevatorOffsetCalculator] Loaded
2023-04-28T16:55:08.024Z,1682700908.024 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-04-28T16:55:08.025Z,1682700908.025 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-04-28T16:55:08.025Z,1682700908.025 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-04-28T16:55:08.156Z,1682700908.156 [CANONSampler] Loaded
2023-04-28T16:55:08.156Z,1682700908.156 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-04-28T16:55:08.216Z,1682700908.216 [CTD_Seabird] Loaded
2023-04-28T16:55:08.216Z,1682700908.216 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-04-28T16:55:08.217Z,1682700908.217 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2023-04-28T16:55:08.218Z,1682700908.218 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1106
2023-04-28T16:55:08.238Z,1682700908.238 [PAR_Licor] Loaded
2023-04-28T16:55:08.238Z,1682700908.238 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-04-28T16:55:08.270Z,1682700908.270 [WetLabsBB2FL] Loaded
2023-04-28T16:55:08.270Z,1682700908.270 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-04-28T16:55:08.271Z,1682700908.271 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2023-04-28T16:55:08.271Z,1682700908.271 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1107
2023-04-28T16:55:08.272Z,1682700908.272 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-04-28T16:55:08.273Z,1682700908.273 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-04-28T16:55:08.298Z,1682700908.298 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-04-28T16:55:08.299Z,1682700908.299 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-04-28T16:55:08.604Z,1682700908.604 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-04-28T16:55:08.605Z,1682700908.605 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-04-28T16:55:08.743Z,1682700908.743 [SBIT](DEBUG): Construct Startup Built In Test.
2023-04-28T16:55:08.752Z,1682700908.752 [SBIT] Loaded
2023-04-28T16:55:08.752Z,1682700908.752 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-04-28T16:55:08.755Z,1682700908.755 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-04-28T16:55:08.768Z,1682700908.768 [IBIT] Loaded
2023-04-28T16:55:08.768Z,1682700908.768 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-04-28T16:55:08.774Z,1682700908.774 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-04-28T16:55:08.881Z,1682700908.881 [CBIT] Loaded
2023-04-28T16:55:08.882Z,1682700908.882 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-04-28T16:55:08.882Z,1682700908.882 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-04-28T16:55:08.883Z,1682700908.883 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-04-28T16:55:09.268Z,1682700909.268 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-04-28T16:55:09.306Z,1682700909.306 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-04-28T16:55:09.309Z,1682700909.309 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-04-28T16:55:09.328Z,1682700909.328 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-04-28T16:55:09.340Z,1682700909.340 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B7E4E0
2023-04-28T16:55:09.341Z,1682700909.341 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1108
2023-04-28T16:55:09.371Z,1682700909.371 [Supervisor](INFO): Main Thread ID is 829
2023-04-28T16:55:09.371Z,1682700909.371 [Supervisor](DEBUG): Running supervisor.
2023-04-28T16:55:09.372Z,1682700909.372 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1109
2023-04-28T16:55:09.372Z,1682700909.372 [CommandExec](INFO): Initializing the command executive.
2023-04-28T16:55:09.380Z,1682700909.380 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1110
2023-04-28T16:55:09.382Z,1682700909.382 [controlThread ThreadHandler](INFO): Handler Thread ID is 1111
2023-04-28T16:55:09.383Z,1682700909.383 [controlThread](DEBUG): Initializing ControlThread
2023-04-28T16:55:09.384Z,1682700909.384 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-04-28T16:55:09.385Z,1682700909.385 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-04-28T16:55:09.386Z,1682700909.386 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-04-28T16:55:09.387Z,1682700909.387 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-04-28T16:55:09.388Z,1682700909.388 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-04-28T16:55:09.389Z,1682700909.389 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-04-28T16:55:09.392Z,1682700909.392 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-04-28T16:55:09.392Z,1682700909.392 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-04-28T16:55:09.393Z,1682700909.393 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-04-28T16:55:09.393Z,1682700909.393 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-04-28T16:55:09.394Z,1682700909.394 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-04-28T16:55:09.394Z,1682700909.394 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-04-28T16:55:09.399Z,1682700909.399 [SBIT](INFO): Initialize SBIT Component.
2023-04-28T16:55:09.399Z,1682700909.399 [SBIT](IMPORTANT): git: 2023-04-14_A
2023-04-28T16:55:09.399Z,1682700909.399 [SBIT](INFO): git hash: a5e7240676c499658609ad29f2edbd696bbbdf6d
2023-04-28T16:55:09.400Z,1682700909.400 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-04-28T16:55:09.401Z,1682700909.401 [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
2023-04-28T16:55:09.402Z,1682700909.402 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2023-04-28T16:55:09.402Z,1682700909.402 [IBIT](INFO): Initialize IBIT Component.
2023-04-28T16:55:09.403Z,1682700909.403 [CBIT](DEBUG): Initialize CBIT Component.
2023-04-28T16:55:09.404Z,1682700909.404 [logger ThreadHandler](INFO): Handler Thread ID is 1112
2023-04-28T16:55:09.420Z,1682700909.420 [CBIT](DEBUG): Initialized mux pins.
2023-04-28T16:55:09.421Z,1682700909.421 [CBIT](DEBUG): Initializing the watchdog timer.
2023-04-28T16:55:09.427Z,1682700909.427 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1113
2023-04-28T16:55:09.428Z,1682700909.428 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-04-28T16:55:09.435Z,1682700909.435 [Onboard ThreadHandler](INFO): Handler Thread ID is 1114
2023-04-28T16:55:09.447Z,1682700909.447 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-04-28T16:55:09.448Z,1682700909.448 [CBIT](DEBUG): Initializing heartbeat.
2023-04-28T16:55:09.456Z,1682700909.456 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1115
2023-04-28T16:55:09.471Z,1682700909.471 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1116
2023-04-28T16:55:09.471Z,1682700909.471 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-04-28T16:55:09.476Z,1682700909.476 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1118
2023-04-28T16:55:09.479Z,1682700909.479 [WetLabsBB2FL](INFO): Powering up
2023-04-28T16:55:09.480Z,1682700909.480 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1120
2023-04-28T16:55:09.483Z,1682700909.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-04-28T16:55:09.483Z,1682700909.483 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-04-28T16:55:09.483Z,1682700909.483 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-04-28T16:55:09.484Z,1682700909.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-04-28T16:55:09.484Z,1682700909.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-04-28T16:55:09.484Z,1682700909.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-04-28T16:55:09.484Z,1682700909.484 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-04-28T16:55:09.484Z,1682700909.484 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-04-28T16:55:09.518Z,1682700909.518 [CBIT](DEBUG): Deactivating GF circuits.
2023-04-28T16:55:09.518Z,1682700909.518 [CBIT](DEBUG): Deactivating emergency mode.
2023-04-28T16:55:09.558Z,1682700909.558 [CBIT](DEBUG): Backplane powered.
2023-04-28T16:55:09.559Z,1682700909.559 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-04-28T16:55:09.579Z,1682700909.579 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-28T16:55:09.591Z,1682700909.591 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-04-28T16:55:09.592Z,1682700909.592 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-04-28T16:55:09.657Z,1682700909.657 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-04-28T16:55:09.674Z,1682700909.674 [Default:A.Wait](DEBUG): Construct Wait.
2023-04-28T16:55:09.677Z,1682700909.677 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-28T16:55:09.709Z,1682700909.709 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-04-28T16:55:09.711Z,1682700909.711 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-04-28T16:55:09.737Z,1682700909.737 [Default:E.Execute](DEBUG): Construct Execute.
2023-04-28T16:55:09.740Z,1682700909.740 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-04-28T16:55:09.758Z,1682700909.758 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-04-28T16:55:09.764Z,1682700909.764 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-04-28T16:55:09.833Z,1682700909.833 [Power24vConverter](INFO): Powering up.
2023-04-28T16:55:09.833Z,1682700909.833 [CANONSampler](INFO): Powering down
2023-04-28T16:55:09.862Z,1682700909.862 [Radio_Surface](INFO): Powering up
2023-04-28T16:55:09.865Z,1682700909.865 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-04-28T16:55:09.870Z,1682700909.870 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-04-28T16:55:09.871Z,1682700909.871 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-04-28T16:55:09.883Z,1682700909.883 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-04-28T16:55:09.883Z,1682700909.883 [MassServo](DEBUG): Initializing EZServoServo.
2023-04-28T16:55:09.891Z,1682700909.891 [MassServo](DEBUG): Initializing MassServo.
2023-04-28T16:55:09.892Z,1682700909.892 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-28T16:55:09.898Z,1682700909.898 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-28T16:55:09.899Z,1682700909.899 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-04-28T16:55:09.906Z,1682700909.906 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-04-28T16:55:12.090Z,1682700912.090 [WetLabsBB2FL](INFO): Powering down
2023-04-28T16:55:38.052Z,1682700938.052 [NAL9602](INFO): Powering up NAL9602
2023-04-28T16:55:48.965Z,1682700948.965 [NAL9602](INFO): NAL9602 initialized
2023-04-28T16:56:12.828Z,1682700972.828 [SBIT](IMPORTANT): Beginning Startup BIT
2023-04-28T16:56:12.833Z,1682700972.833 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-28T16:56:23.761Z,1682700983.761 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-28T16:56:24.150Z,1682700984.150 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-28T16:56:35.159Z,1682700995.159 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-28T16:56:35.547Z,1682700995.547 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-28T16:56:46.407Z,1682701006.407 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-04-28T16:56:46.407Z,1682701006.407 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-04-28T16:57:07.027Z,1682701027.027 [SBIT](IMPORTANT): SBIT PASSED
2023-04-28T16:57:07.027Z,1682701027.027 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-04-28T16:57:07.028Z,1682701027.028 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-04-28T16:57:07.028Z,1682701027.028 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;

2023-04-28T16:57:07.028Z,1682701027.028 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index 0.050000 practical_salinity_unit;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1=36.81 degree;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2=36.81 degree;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3=36.78 degree;

2023-04-28T16:57:07.029Z,1682701027.029 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4=36.78 degree;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=0 bool;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1=-121.87 degree;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2=-121.83 degree;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3=-121.83 degree;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4=-121.87 degree;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=210.806836 cubic_centimeter;

2023-04-28T16:57:07.030Z,1682701027.030 [SBIT](IMPORTANT): VerticalControl.massDefault=-10.801766 millimeter;

2023-04-28T16:57:07.031Z,1682701027.031 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-04-28T16:57:07.031Z,1682701027.031 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-04-28T16:57:07.031Z,1682701027.031 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-04-28T16:57:07.364Z,1682701027.364 [MissionManager](IMPORTANT): Started mission Startup
2023-04-28T16:57:07.365Z,1682701027.365 [Startup] Running Loop=1
2023-04-28T16:57:07.365Z,1682701027.365 [Startup](DEBUG): Aggregate::initialize Startup
2023-04-28T16:57:07.365Z,1682701027.365 [Startup:A.GoToSurface] Running Loop=1
2023-04-28T16:57:07.365Z,1682701027.365 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-28T16:57:07.366Z,1682701027.366 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-28T16:57:07.366Z,1682701027.366 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-28T16:57:07.367Z,1682701027.367 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-28T16:57:07.367Z,1682701027.367 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-28T16:57:07.367Z,1682701027.367 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-28T16:57:07.369Z,1682701027.369 [Startup:StartupSatComms] Running Loop=1
2023-04-28T16:57:07.369Z,1682701027.369 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-04-28T16:57:07.369Z,1682701027.369 [Startup:StartupSatComms:A] Running Loop=1
2023-04-28T16:57:07.769Z,1682701027.769 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-04-28T16:58:07.553Z,1682701087.553 [Startup:StartupSatComms:A](INFO): Timed out from 2023-04-28T16:57:07.4Z
2023-04-28T16:58:07.553Z,1682701087.553 [Startup:StartupSatComms:A] Stopped
2023-04-28T16:58:07.553Z,1682701087.553 [Startup:StartupSatComms:B] Running Loop=1
2023-04-28T16:58:07.948Z,1682701087.948 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-28T16:58:09.608Z,1682701089.608 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-04-28T16:58:09.608Z,1682701089.608 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-28T16:58:09.640Z,1682701089.640 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-28T16:58:09.975Z,1682701089.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-28T16:58:09.975Z,1682701089.975 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-04-28T16:58:43.704Z,1682701123.704 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004618
2023-04-28T16:58:53.266Z,1682701133.266 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230428T162910/Courier0010.lzma
2023-04-28T16:58:54.269Z,1682701134.269 [DataOverHttps](INFO): Moved sent file to Logs/20230428T162910/Courier0010.lzma.bak
2023-04-28T16:58:54.269Z,1682701134.269 [DataOverHttps](INFO): SBD MOMSN=18272418
2023-04-28T16:59:07.789Z,1682701147.789 [Startup:StartupSatComms:B](INFO): Timed out from 2023-04-28T16:58:07.6Z
2023-04-28T16:59:07.789Z,1682701147.789 [Startup:StartupSatComms:B] Stopped
2023-04-28T16:59:07.789Z,1682701147.789 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-04-28T16:59:07.789Z,1682701147.789 [Startup:StartupSatComms] Stopped
2023-04-28T16:59:07.789Z,1682701147.789 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-04-28T16:59:07.794Z,1682701147.794 [Startup](INFO): Completed Startup
2023-04-28T16:59:07.795Z,1682701147.795 [MissionManager](INFO): Startup is completed.
2023-04-28T16:59:07.795Z,1682701147.795 [MissionManager](INFO): Uninitializing Mission Startup
2023-04-28T16:59:07.795Z,1682701147.795 [Startup] Stopped
2023-04-28T16:59:07.795Z,1682701147.795 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-04-28T16:59:07.795Z,1682701147.795 [Startup:A.GoToSurface] Stopped
2023-04-28T16:59:07.795Z,1682701147.795 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-28T16:59:08.184Z,1682701148.184 [MissionManager](IMPORTANT): Started mission Default
2023-04-28T16:59:08.184Z,1682701148.184 [Default] Running Loop=1
2023-04-28T16:59:08.184Z,1682701148.184 [Default](DEBUG): Aggregate::initialize Default
2023-04-28T16:59:08.185Z,1682701148.185 [Default:B.GoToSurface] Running Loop=1
2023-04-28T16:59:08.185Z,1682701148.185 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-28T16:59:08.185Z,1682701148.185 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-28T16:59:08.185Z,1682701148.185 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-28T16:59:08.185Z,1682701148.185 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-28T16:59:08.186Z,1682701148.186 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-28T16:59:08.187Z,1682701148.187 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-28T16:59:08.187Z,1682701148.187 [Default:A.Wait] Running Loop=1
2023-04-28T16:59:08.187Z,1682701148.187 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-04-28T16:59:10.354Z,1682701150.354 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230428T165459/Courier0000.lzma
2023-04-28T16:59:11.356Z,1682701151.356 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Courier0000.lzma.bak
2023-04-28T16:59:11.356Z,1682701151.356 [DataOverHttps](INFO): SBD MOMSN=18272420
2023-04-28T16:59:21.545Z,1682701161.545 [Default:A.Wait](INFO): Done Waiting.
2023-04-28T16:59:21.545Z,1682701161.545 [Default:A.Wait] Stopped
2023-04-28T16:59:21.545Z,1682701161.545 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-04-28T16:59:21.934Z,1682701161.934 [Default:CheckIn] Running Loop=1
2023-04-28T16:59:21.934Z,1682701161.934 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-28T16:59:21.934Z,1682701161.934 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-28T16:59:22.333Z,1682701162.333 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-04-28T17:00:52.432Z,1682701252.432 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-28T17:01:10.633Z,1682701270.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-04-28T17:01:10.633Z,1682701270.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-28T17:01:10.664Z,1682701270.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-28T17:01:11.079Z,1682701271.079 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-28T17:01:11.079Z,1682701271.079 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-04-28T17:01:50.603Z,1682701310.603 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:01:57.881Z,1682701317.881 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:02:12.823Z,1682701332.823 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:02:28.202Z,1682701348.202 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:02:43.147Z,1682701363.147 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:02:58.548Z,1682701378.548 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:03:13.898Z,1682701393.898 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:03:28.448Z,1682701408.448 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:03:43.796Z,1682701423.796 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:03:59.147Z,1682701439.147 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:04:11.684Z,1682701451.684 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-04-28T17:04:11.684Z,1682701451.684 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-28T17:04:11.696Z,1682701451.696 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-28T17:04:12.109Z,1682701452.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-28T17:04:12.109Z,1682701452.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-04-28T17:04:22.299Z,1682701462.299 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-28T16:59:21.9Z
2023-04-28T17:04:22.299Z,1682701462.299 [Default:CheckIn:Read_GPS] Stopped
2023-04-28T17:04:22.299Z,1682701462.299 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-28T17:04:22.693Z,1682701462.693 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-28T17:04:25.409Z,1682701465.409 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230428T165459/Courier0004.lzma
2023-04-28T17:04:26.412Z,1682701466.412 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Courier0004.lzma.bak
2023-04-28T17:04:26.412Z,1682701466.412 [DataOverHttps](INFO): SBD MOMSN=18272424
2023-04-28T17:04:41.870Z,1682701481.870 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230428T162910/Express0011.lzma
2023-04-28T17:04:42.872Z,1682701482.872 [DataOverHttps](INFO): Moved sent file to Logs/20230428T162910/Express0011.lzma.bak
2023-04-28T17:04:42.872Z,1682701482.872 [DataOverHttps](INFO): SBD MOMSN=18272426
2023-04-28T17:05:01.231Z,1682701501.231 [DataOverHttps](INFO): Sending 929 bytes from file Logs/20230428T165459/Express0001.lzma
2023-04-28T17:05:02.235Z,1682701502.235 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Express0001.lzma.bak
2023-04-28T17:05:02.235Z,1682701502.235 [DataOverHttps](INFO): SBD MOMSN=18272434
2023-04-28T17:05:11.031Z,1682701511.031 [Power24vConverter](INFO): Powering down.
2023-04-28T17:05:17.750Z,1682701517.750 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230428T165459/Express0005.lzma
2023-04-28T17:05:18.752Z,1682701518.752 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Express0005.lzma.bak
2023-04-28T17:05:18.752Z,1682701518.752 [DataOverHttps](INFO): SBD MOMSN=18272469
2023-04-28T17:05:19.925Z,1682701519.925 [Default:CheckIn:Read_Iridium] Stopped
2023-04-28T17:05:19.925Z,1682701519.925 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-28T17:05:19.925Z,1682701519.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-28T17:05:49.796Z,1682701549.796 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-28T17:05:49.796Z,1682701549.796 [NAL9602] Data Fault, FailCount= 1
2023-04-28T17:05:49.796Z,1682701549.796 [NAL9602](ERROR): Data Fault
2023-04-28T17:05:49.818Z,1682701549.818 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-28T17:05:50.204Z,1682701550.204 [NAL9602](INFO): Powering down
2023-04-28T17:05:51.028Z,1682701551.028 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-28T17:05:51.028Z,1682701551.028 [NAL9602] No Fault, FailCount= 1
2023-04-28T17:06:20.500Z,1682701580.500 [NAL9602](INFO): Powering up NAL9602
2023-04-28T17:06:31.432Z,1682701591.432 [NAL9602](INFO): NAL9602 initialized
2023-04-28T17:07:12.688Z,1682701632.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-04-28T17:07:12.688Z,1682701632.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-28T17:07:12.698Z,1682701632.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-28T17:07:13.105Z,1682701633.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-28T17:07:13.105Z,1682701633.105 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-04-28T17:10:13.717Z,1682701813.717 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-04-28T17:10:13.717Z,1682701813.717 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-28T17:10:13.727Z,1682701813.727 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-28T17:10:14.143Z,1682701814.143 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-28T17:10:14.143Z,1682701814.143 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-04-28T17:10:20.551Z,1682701820.551 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-28T17:10:20.551Z,1682701820.551 [Default:CheckIn:C.Wait] Stopped
2023-04-28T17:10:20.551Z,1682701820.551 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-28T17:10:20.552Z,1682701820.552 [Default:CheckIn:D] Running Loop=1
2023-04-28T17:10:20.970Z,1682701820.970 [Default:CheckIn:D] Stopped
2023-04-28T17:10:20.970Z,1682701820.970 [Default:CheckIn:E] Running Loop=1
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.213088 min 
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn:E] Stopped
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn] Stopped
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn](INFO): Running loop #2
2023-04-28T17:10:21.360Z,1682701821.360 [Default:CheckIn] Running Loop=2
2023-04-28T17:10:21.361Z,1682701821.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-28T17:10:21.361Z,1682701821.361 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-28T17:11:25.183Z,1682701885.183 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:11:40.136Z,1682701900.136 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:11:55.887Z,1682701915.887 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:12:10.834Z,1682701930.834 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:12:25.783Z,1682701945.783 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:12:40.731Z,1682701960.731 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:12:55.678Z,1682701975.678 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:13:10.639Z,1682701990.639 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:13:14.698Z,1682701994.698 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-04-28T17:13:14.698Z,1682701994.698 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-28T17:13:14.717Z,1682701994.717 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-28T17:13:15.096Z,1682701995.096 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-28T17:13:15.096Z,1682701995.096 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-04-28T17:13:15.892Z,1682701995.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171314.00,A,3648.16139,N,12147.24178,W,3.032,220.67,280423,,,A*7D
2023-04-28T17:13:15.895Z,1682701995.895 [NAL9602](INFO): GPS fix at 20230428T171314: (36.802690, -121.787363)
2023-04-28T17:13:15.906Z,1682701995.906 [Default:CheckIn:Read_GPS] Stopped
2023-04-28T17:13:15.906Z,1682701995.906 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-28T17:13:24.358Z,1682702004.358 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230428T165459/Courier0007.lzma
2023-04-28T17:13:25.371Z,1682702005.371 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Courier0007.lzma.bak
2023-04-28T17:13:25.374Z,1682702005.374 [DataOverHttps](INFO): SBD MOMSN=18272473
2023-04-28T17:13:25.988Z,1682702005.988 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:13:40.940Z,1682702020.940 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-04-28T17:13:41.590Z,1682702021.590 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20230428T165459/Express0008.lzma
2023-04-28T17:13:42.592Z,1682702022.592 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Express0008.lzma.bak
2023-04-28T17:13:42.592Z,1682702022.592 [DataOverHttps](INFO): SBD MOMSN=18272476
2023-04-28T17:13:43.776Z,1682702023.776 [Default:CheckIn:Read_Iridium] Stopped
2023-04-28T17:13:43.776Z,1682702023.776 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-28T17:13:43.777Z,1682702023.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-28T17:13:48.615Z,1682702028.615 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-28T17:18:44.209Z,1682702324.209 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-28T17:18:44.209Z,1682702324.209 [Default:CheckIn:C.Wait] Stopped
2023-04-28T17:18:44.209Z,1682702324.209 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-28T17:18:44.209Z,1682702324.209 [Default:CheckIn:D] Running Loop=1
2023-04-28T17:18:44.609Z,1682702324.609 [Default:CheckIn:D] Stopped
2023-04-28T17:18:44.609Z,1682702324.609 [Default:CheckIn:E] Running Loop=1
2023-04-28T17:18:45.026Z,1682702325.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.607080 min 
2023-04-28T17:18:45.026Z,1682702325.026 [Default:CheckIn:E] Stopped
2023-04-28T17:18:45.026Z,1682702325.026 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-28T17:18:45.026Z,1682702325.026 [Default:CheckIn] Stopped
2023-04-28T17:18:45.026Z,1682702325.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-28T17:18:45.027Z,1682702325.027 [Default:CheckIn](INFO): Running loop #3
2023-04-28T17:18:45.027Z,1682702325.027 [Default:CheckIn] Running Loop=3
2023-04-28T17:18:45.027Z,1682702325.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-28T17:18:45.027Z,1682702325.027 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-28T17:18:47.025Z,1682702327.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171846.00,A,3648.14022,N,12147.22773,W,0.019,72.65,280423,,,D*4B
2023-04-28T17:18:47.028Z,1682702327.028 [NAL9602](INFO): GPS fix at 20230428T171846: (36.802337, -121.787129)
2023-04-28T17:18:47.048Z,1682702327.048 [Default:CheckIn:Read_GPS] Stopped
2023-04-28T17:18:47.048Z,1682702327.048 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-28T17:18:58.258Z,1682702338.258 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230428T165459/Courier0010.lzma
2023-04-28T17:18:59.260Z,1682702339.260 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Courier0010.lzma.bak
2023-04-28T17:18:59.260Z,1682702339.260 [DataOverHttps](INFO): SBD MOMSN=18272480
2023-04-28T17:19:10.529Z,1682702350.529 [NAL9602](INFO): SBD MO Status=0, MOMSN=42692, MT Status=0, MTMSN=0
2023-04-28T17:19:10.529Z,1682702350.529 [NAL9602](INFO): No messages in MT queue
2023-04-28T17:19:15.270Z,1682702355.270 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230428T165459/Express0011.lzma
2023-04-28T17:19:16.272Z,1682702356.272 [DataOverHttps](INFO): Moved sent file to Logs/20230428T165459/Express0011.lzma.bak
2023-04-28T17:19:16.272Z,1682702356.272 [DataOverHttps](INFO): SBD MOMSN=18272483
2023-04-28T17:19:17.421Z,1682702357.421 [Default:CheckIn:Read_Iridium] Stopped
2023-04-28T17:19:17.422Z,1682702357.422 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-28T17:19:17.422Z,1682702357.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-28T17:19:41.231Z,1682702381.231 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-28T17:20:01.432Z,1682702401.432 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-04-28T17:20:55.928Z,1682702455.928 [CommandExec](IMPORTANT): got command restart application
2023-04-28T17:20:56.930Z,1682702456.930 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-04-28T17:20:56.931Z,1682702456.931 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:20:56.931Z,1682702456.931 [CommandExec](INFO): Uninitializing the command executive.
2023-04-28T17:20:56.931Z,1682702456.931 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-28T17:20:56.931Z,1682702456.931 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:56.954Z,1682702456.954 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-28T17:20:56.954Z,1682702456.954 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-28T17:20:56.954Z,1682702456.954 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:56.955Z,1682702456.955 [NavChartDb](INFO): Join timeout helper Thread ID is 1259
2023-04-28T17:20:57.142Z,1682702457.142 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:20:57.143Z,1682702457.143 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.143Z,1682702457.143 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-28T17:20:57.143Z,1682702457.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.144Z,1682702457.144 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1260
2023-04-28T17:20:57.166Z,1682702457.166 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:20:57.167Z,1682702457.167 [WetLabsBB2FL](INFO): Powering down
2023-04-28T17:20:57.167Z,1682702457.167 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.168Z,1682702457.168 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-28T17:20:57.168Z,1682702457.168 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.168Z,1682702457.168 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1261
2023-04-28T17:20:57.286Z,1682702457.286 [CTD_Seabird](INFO): Powering down
2023-04-28T17:20:57.298Z,1682702457.298 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:20:57.299Z,1682702457.299 [CTD_Seabird](INFO): Powering down
2023-04-28T17:20:57.310Z,1682702457.310 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.314Z,1682702457.314 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-28T17:20:57.314Z,1682702457.314 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.315Z,1682702457.315 [Radio_Surface](INFO): Join timeout helper Thread ID is 1262
2023-04-28T17:20:57.650Z,1682702457.650 [Radio_Surface](INFO): Powering down
2023-04-28T17:20:57.651Z,1682702457.651 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:20:57.652Z,1682702457.652 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.674Z,1682702457.674 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-28T17:20:57.674Z,1682702457.674 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:20:57.675Z,1682702457.675 [Onboard](INFO): Join timeout helper Thread ID is 1263
2023-04-28T17:20:58.109Z,1682702458.109 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-04-28T17:21:00.778Z,1682702460.778 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:21:00.779Z,1682702460.779 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:00.783Z,1682702460.783 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-28T17:21:00.783Z,1682702460.783 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:00.784Z,1682702460.784 [DataOverHttps](INFO): Join timeout helper Thread ID is 1264
2023-04-28T17:21:01.487Z,1682702461.487 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:21:01.489Z,1682702461.489 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.503Z,1682702461.503 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-28T17:21:01.503Z,1682702461.503 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.503Z,1682702461.503 [logger](INFO): Join timeout helper Thread ID is 1265
2023-04-28T17:21:01.538Z,1682702461.538 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:21:01.539Z,1682702461.539 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.543Z,1682702461.543 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-28T17:21:01.543Z,1682702461.543 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.543Z,1682702461.543 [CommandLine](INFO): Join timeout helper Thread ID is 1266
2023-04-28T17:21:01.634Z,1682702461.634 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:21:01.635Z,1682702461.635 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.643Z,1682702461.643 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-28T17:21:01.643Z,1682702461.643 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.643Z,1682702461.643 [CommandExec](INFO): Join timeout helper Thread ID is 1267
2023-04-28T17:21:01.644Z,1682702461.644 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-28T17:21:01.645Z,1682702461.645 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.645Z,1682702461.645 [controlThread](INFO): Join timeout helper Thread ID is 1268
2023-04-28T17:21:01.722Z,1682702461.722 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-28T17:21:01.723Z,1682702461.723 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-28T17:21:01.723Z,1682702461.723 [AHRS_M2](INFO): Powering down
2023-04-28T17:21:01.795Z,1682702461.795 [NAL9602](INFO): Powering down
2023-04-28T17:21:01.798Z,1682702461.798 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-28T17:21:01.799Z,1682702461.799 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-28T17:21:01.799Z,1682702461.799 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-28T17:21:01.800Z,1682702461.800 [MissionManager](INFO): Uninitializing Mission Default
2023-04-28T17:21:01.800Z,1682702461.800 [Default] Stopped
2023-04-28T17:21:01.800Z,1682702461.800 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-28T17:21:01.800Z,1682702461.800 [Default:B.GoToSurface] Stopped
2023-04-28T17:21:01.800Z,1682702461.800 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-28T17:21:01.800Z,1682702461.800 [Default:CheckIn] Stopped
2023-04-28T17:21:01.800Z,1682702461.800 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-28T17:21:01.801Z,1682702461.801 [Default:CheckIn:C.Wait] Stopped
2023-04-28T17:21:01.801Z,1682702461.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-28T17:21:01.803Z,1682702461.803 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-28T17:21:01.804Z,1682702461.804 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-28T17:21:01.804Z,1682702461.804 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-28T17:21:01.804Z,1682702461.804 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-28T17:21:01.805Z,1682702461.805 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-28T17:21:01.805Z,1682702461.805 [BuoyancyServo](INFO): Powering down
2023-04-28T17:21:01.818Z,1682702461.818 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-28T17:21:01.818Z,1682702461.818 [ElevatorServo](INFO): Powering down
2023-04-28T17:21:01.819Z,1682702461.819 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-28T17:21:01.819Z,1682702461.819 [MassServo](INFO): Powering down
2023-04-28T17:21:01.820Z,1682702461.820 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-28T17:21:01.820Z,1682702461.820 [RudderServo](INFO): Powering down
2023-04-28T17:21:01.821Z,1682702461.821 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-04-28T17:21:01.821Z,1682702461.821 [ThrusterServo](INFO): Powering down
2023-04-28T17:21:01.822Z,1682702461.822 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-28T17:21:01.823Z,1682702461.823 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-28T17:21:01.823Z,1682702461.823 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-28T17:21:01.823Z,1682702461.823 [CBIT](DEBUG): Powering off loads.
2023-04-28T17:21:01.834Z,1682702461.834 [CBIT](DEBUG): Disabling WDT.
2023-04-28T17:21:01.846Z,1682702461.846 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-28T17:21:01.847Z,1682702461.847 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.900Z,1682702461.900 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.903Z,1682702461.903 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.909Z,1682702461.909 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.981Z,1682702461.981 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:01.985Z,1682702461.985 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:02.025Z,1682702462.025 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-28T17:21:02.095Z,1682702462.095 [logger ThreadHandler](INFO): Thread cancelled.