2023-01-17T16:27:35.076Z,1673972855.076 [Supervisor](DEBUG): Initializing supervisor.
2023-01-17T16:27:35.081Z,1673972855.081 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-01-17T16:27:35.081Z,1673972855.081 [SyncHandler](INFO): Protected caller Thread ID is 1644
2023-01-17T16:27:35.082Z,1673972855.082 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-01-17T16:27:35.083Z,1673972855.083 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-01-17T16:27:35.084Z,1673972855.084 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1645
2023-01-17T16:27:35.088Z,1673972855.088 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-01-17T16:27:35.105Z,1673972855.105 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-01-17T16:27:35.106Z,1673972855.106 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-01-17T16:27:35.106Z,1673972855.106 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1646
2023-01-17T16:27:35.111Z,1673972855.111 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-01-17T16:27:35.112Z,1673972855.112 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-01-17T16:27:35.112Z,1673972855.112 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1647
2023-01-17T16:27:35.114Z,1673972855.114 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-01-17T16:27:35.115Z,1673972855.115 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-01-17T16:27:35.116Z,1673972855.116 [logger ThreadHandler](INFO): Protected caller Thread ID is 1648
2023-01-17T16:27:35.119Z,1673972855.119 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-01-17T16:27:35.119Z,1673972855.119 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-01-17T16:27:35.121Z,1673972855.121 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-01-17T16:27:35.378Z,1673972855.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-01-17T16:27:35.379Z,1673972855.379 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-01-17T16:27:35.458Z,1673972855.458 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-01-17T16:27:35.909Z,1673972855.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-01-17T16:27:35.910Z,1673972855.910 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-01-17T16:27:36.217Z,1673972856.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-01-17T16:27:36.218Z,1673972856.218 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-01-17T16:27:36.312Z,1673972856.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-01-17T16:27:36.313Z,1673972856.313 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-01-17T16:27:36.685Z,1673972856.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-01-17T16:27:36.686Z,1673972856.686 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-01-17T16:27:36.886Z,1673972856.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-01-17T16:27:36.887Z,1673972856.887 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-01-17T16:27:37.364Z,1673972857.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-01-17T16:27:37.365Z,1673972857.365 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-01-17T16:27:37.497Z,1673972857.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-01-17T16:27:37.498Z,1673972857.498 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-01-17T16:27:37.577Z,1673972857.577 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-01-17T16:27:38.281Z,1673972858.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-01-17T16:27:38.281Z,1673972858.281 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-01-17T16:27:38.631Z,1673972858.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-01-17T16:27:38.632Z,1673972858.632 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-01-17T16:27:38.815Z,1673972858.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-01-17T16:27:38.816Z,1673972858.816 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-01-17T16:27:38.926Z,1673972858.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-01-17T16:27:38.926Z,1673972858.926 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-01-17T16:27:39.140Z,1673972859.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-01-17T16:27:39.141Z,1673972859.141 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-01-17T16:27:39.384Z,1673972859.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-01-17T16:27:39.386Z,1673972859.386 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-01-17T16:27:39.387Z,1673972859.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-01-17T16:27:39.652Z,1673972859.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-01-17T16:27:39.815Z,1673972859.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-01-17T16:27:40.046Z,1673972860.046 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-01-17T16:27:40.197Z,1673972860.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-01-17T16:27:40.345Z,1673972860.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-01-17T16:27:40.484Z,1673972860.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-01-17T16:27:40.661Z,1673972860.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-01-17T16:27:40.737Z,1673972860.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-01-17T16:27:40.879Z,1673972860.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-01-17T16:27:41.010Z,1673972861.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-01-17T16:27:41.122Z,1673972861.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-01-17T16:27:41.379Z,1673972861.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-01-17T16:27:41.381Z,1673972861.381 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-01-17T16:27:41.381Z,1673972861.381 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-01-17T16:27:41.395Z,1673972861.395 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-01-17T16:27:41.445Z,1673972861.445 [VerticalControl](DEBUG): Construct VerticalControl.
2023-01-17T16:27:41.504Z,1673972861.504 [VerticalControl] Loaded
2023-01-17T16:27:41.505Z,1673972861.505 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-01-17T16:27:41.507Z,1673972861.507 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-01-17T16:27:41.548Z,1673972861.548 [HorizontalControl] Loaded
2023-01-17T16:27:41.548Z,1673972861.548 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-01-17T16:27:41.551Z,1673972861.551 [SpeedControl](DEBUG): Construct SpeedControl.
2023-01-17T16:27:41.554Z,1673972861.554 [SpeedControl] Loaded
2023-01-17T16:27:41.554Z,1673972861.554 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-01-17T16:27:41.557Z,1673972861.557 [LoopControl](DEBUG): Construct LoopControl.
2023-01-17T16:27:41.558Z,1673972861.558 [LoopControl] Loaded
2023-01-17T16:27:41.558Z,1673972861.558 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-01-17T16:27:41.558Z,1673972861.558 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-01-17T16:27:41.559Z,1673972861.559 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-01-17T16:27:41.569Z,1673972861.569 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-01-17T16:27:41.569Z,1673972861.569 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-01-17T16:27:41.716Z,1673972861.716 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-01-17T16:27:41.716Z,1673972861.716 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-01-17T16:27:41.867Z,1673972861.867 [BuoyancyServo] Loaded
2023-01-17T16:27:41.867Z,1673972861.867 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-01-17T16:27:41.887Z,1673972861.887 [ElevatorServo] Loaded
2023-01-17T16:27:41.887Z,1673972861.887 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-01-17T16:27:41.906Z,1673972861.906 [MassServo] Loaded
2023-01-17T16:27:41.906Z,1673972861.906 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-01-17T16:27:41.924Z,1673972861.924 [RudderServo] Loaded
2023-01-17T16:27:41.924Z,1673972861.924 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-01-17T16:27:41.941Z,1673972861.941 [ThrusterServo] Loaded
2023-01-17T16:27:41.941Z,1673972861.941 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-01-17T16:27:41.942Z,1673972861.942 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-01-17T16:27:41.942Z,1673972861.942 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-01-17T16:27:42.052Z,1673972862.052 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-01-17T16:27:42.052Z,1673972862.052 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-01-17T16:27:42.066Z,1673972862.066 [NavChart] Loaded
2023-01-17T16:27:42.067Z,1673972862.067 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-01-17T16:27:42.072Z,1673972862.072 [UniversalFixResidualReporter] Loaded
2023-01-17T16:27:42.072Z,1673972862.072 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-01-17T16:27:42.073Z,1673972862.073 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-01-17T16:27:42.073Z,1673972862.073 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-01-17T16:27:42.129Z,1673972862.129 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-01-17T16:27:42.129Z,1673972862.129 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-01-17T16:27:42.509Z,1673972862.509 [AHRS_M2] Loaded
2023-01-17T16:27:42.509Z,1673972862.509 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-01-17T16:27:43.316Z,1673972863.316 [BPC1] Loaded
2023-01-17T16:27:43.316Z,1673972863.316 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-01-17T16:27:43.388Z,1673972863.388 [DataOverHttps] Loaded
2023-01-17T16:27:43.388Z,1673972863.388 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-01-17T16:27:43.389Z,1673972863.389 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408EE4E0
2023-01-17T16:27:43.390Z,1673972863.390 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1733
2023-01-17T16:27:43.410Z,1673972863.410 [Depth_Keller] Loaded
2023-01-17T16:27:43.410Z,1673972863.410 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-01-17T16:27:43.415Z,1673972863.415 [DropWeight] Loaded
2023-01-17T16:27:43.415Z,1673972863.415 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-01-17T16:27:43.476Z,1673972863.476 [NAL9602] Loaded
2023-01-17T16:27:43.476Z,1673972863.476 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-01-17T16:27:43.505Z,1673972863.505 [Onboard] Loaded
2023-01-17T16:27:43.505Z,1673972863.505 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-01-17T16:27:43.506Z,1673972863.506 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4091E4E0
2023-01-17T16:27:43.506Z,1673972863.506 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1734
2023-01-17T16:27:43.519Z,1673972863.519 [Power24vConverter] Loaded
2023-01-17T16:27:43.520Z,1673972863.520 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-01-17T16:27:43.533Z,1673972863.533 [Radio_Surface] Loaded
2023-01-17T16:27:43.533Z,1673972863.533 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-01-17T16:27:43.534Z,1673972863.534 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4094E4E0
2023-01-17T16:27:43.534Z,1673972863.534 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1735
2023-01-17T16:27:43.535Z,1673972863.535 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-01-17T16:27:43.536Z,1673972863.536 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-01-17T16:27:43.586Z,1673972863.586 [DepthRateCalculator] Loaded
2023-01-17T16:27:43.586Z,1673972863.586 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-01-17T16:27:43.591Z,1673972863.591 [PitchRateCalculator] Loaded
2023-01-17T16:27:43.591Z,1673972863.591 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-01-17T16:27:43.600Z,1673972863.600 [SpeedCalculator] Loaded
2023-01-17T16:27:43.600Z,1673972863.600 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-01-17T16:27:43.604Z,1673972863.604 [YawRateCalculator] Loaded
2023-01-17T16:27:43.605Z,1673972863.605 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-01-17T16:27:43.623Z,1673972863.623 [ElevatorOffsetCalculator] Loaded
2023-01-17T16:27:43.623Z,1673972863.623 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-01-17T16:27:43.624Z,1673972863.624 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-01-17T16:27:43.625Z,1673972863.625 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-01-17T16:27:43.751Z,1673972863.751 [CANONSampler] Loaded
2023-01-17T16:27:43.751Z,1673972863.751 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-01-17T16:27:43.810Z,1673972863.810 [CTD_Seabird] Loaded
2023-01-17T16:27:43.810Z,1673972863.810 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-01-17T16:27:43.811Z,1673972863.811 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A074E0
2023-01-17T16:27:43.811Z,1673972863.811 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1736
2023-01-17T16:27:43.831Z,1673972863.831 [PAR_Licor] Loaded
2023-01-17T16:27:43.832Z,1673972863.832 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-01-17T16:27:43.863Z,1673972863.863 [WetLabsBB2FL] Loaded
2023-01-17T16:27:43.863Z,1673972863.863 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-01-17T16:27:43.864Z,1673972863.864 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0
2023-01-17T16:27:43.864Z,1673972863.864 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1737
2023-01-17T16:27:43.865Z,1673972863.865 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-01-17T16:27:43.866Z,1673972863.866 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-01-17T16:27:43.891Z,1673972863.891 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-01-17T16:27:43.892Z,1673972863.892 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-01-17T16:27:44.198Z,1673972864.198 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-01-17T16:27:44.199Z,1673972864.199 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-01-17T16:27:44.336Z,1673972864.336 [SBIT](DEBUG): Construct Startup Built In Test.
2023-01-17T16:27:44.345Z,1673972864.345 [SBIT] Loaded
2023-01-17T16:27:44.345Z,1673972864.345 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-01-17T16:27:44.348Z,1673972864.348 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-01-17T16:27:44.361Z,1673972864.361 [IBIT] Loaded
2023-01-17T16:27:44.361Z,1673972864.361 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-01-17T16:27:44.367Z,1673972864.367 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-01-17T16:27:44.470Z,1673972864.470 [CBIT] Loaded
2023-01-17T16:27:44.470Z,1673972864.470 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-01-17T16:27:44.471Z,1673972864.471 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-01-17T16:27:44.471Z,1673972864.471 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-01-17T16:27:44.931Z,1673972864.931 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-01-17T16:27:44.946Z,1673972864.946 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-01-17T16:27:44.948Z,1673972864.948 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-01-17T16:27:45.027Z,1673972865.027 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-01-17T16:27:45.040Z,1673972865.040 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6F4E0
2023-01-17T16:27:45.040Z,1673972865.040 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1738
2023-01-17T16:27:45.045Z,1673972865.045 [Supervisor](INFO): Main Thread ID is 830
2023-01-17T16:27:45.045Z,1673972865.045 [Supervisor](DEBUG): Running supervisor.
2023-01-17T16:27:45.046Z,1673972865.046 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1739
2023-01-17T16:27:45.046Z,1673972865.046 [CommandExec](INFO): Initializing the command executive.
2023-01-17T16:27:45.079Z,1673972865.079 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1740
2023-01-17T16:27:45.120Z,1673972865.120 [controlThread ThreadHandler](INFO): Handler Thread ID is 1741
2023-01-17T16:27:45.120Z,1673972865.120 [controlThread](DEBUG): Initializing ControlThread
2023-01-17T16:27:45.121Z,1673972865.121 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-01-17T16:27:45.123Z,1673972865.123 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-01-17T16:27:45.123Z,1673972865.123 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-01-17T16:27:45.124Z,1673972865.124 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-01-17T16:27:45.126Z,1673972865.126 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-01-17T16:27:45.126Z,1673972865.126 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-01-17T16:27:45.133Z,1673972865.133 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-01-17T16:27:45.134Z,1673972865.134 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-01-17T16:27:45.134Z,1673972865.134 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-01-17T16:27:45.134Z,1673972865.134 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-01-17T16:27:45.134Z,1673972865.134 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-01-17T16:27:45.140Z,1673972865.140 [SBIT](INFO): Initialize SBIT Component.
2023-01-17T16:27:45.140Z,1673972865.140 [SBIT](IMPORTANT): git: 2023-01-12
2023-01-17T16:27:45.140Z,1673972865.140 [SBIT](INFO): git hash: 9912d4ecea2258dc5fc1412f04f143c5e875fcb2
2023-01-17T16:27:45.141Z,1673972865.141 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-01-17T16:27:45.142Z,1673972865.142 [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-01-17T16:27:45.143Z,1673972865.143 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2023-01-17T16:27:45.144Z,1673972865.144 [IBIT](INFO): Initialize IBIT Component.
2023-01-17T16:27:45.145Z,1673972865.145 [CBIT](DEBUG): Initialize CBIT Component.
2023-01-17T16:27:45.146Z,1673972865.146 [logger ThreadHandler](INFO): Handler Thread ID is 1742
2023-01-17T16:27:45.147Z,1673972865.147 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1743
2023-01-17T16:27:45.150Z,1673972865.150 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-01-17T16:27:45.155Z,1673972865.155 [CBIT](DEBUG): Initialized mux pins.
2023-01-17T16:27:45.156Z,1673972865.156 [CBIT](DEBUG): Initializing the watchdog timer.
2023-01-17T16:27:45.176Z,1673972865.176 [Onboard ThreadHandler](INFO): Handler Thread ID is 1744
2023-01-17T16:27:45.195Z,1673972865.195 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-01-17T16:27:45.196Z,1673972865.196 [CBIT](DEBUG): Initializing heartbeat.
2023-01-17T16:27:45.204Z,1673972865.204 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1745
2023-01-17T16:27:45.204Z,1673972865.204 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1746
2023-01-17T16:27:45.205Z,1673972865.205 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-01-17T16:27:45.207Z,1673972865.207 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1748
2023-01-17T16:27:45.210Z,1673972865.210 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1749
2023-01-17T16:27:45.213Z,1673972865.213 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-01-17T16:27:45.213Z,1673972865.213 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-01-17T16:27:45.213Z,1673972865.213 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-01-17T16:27:45.213Z,1673972865.213 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-01-17T16:27:45.214Z,1673972865.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-01-17T16:27:45.214Z,1673972865.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-01-17T16:27:45.214Z,1673972865.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-01-17T16:27:45.214Z,1673972865.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-01-17T16:27:45.244Z,1673972865.244 [WetLabsBB2FL](INFO): Powering up
2023-01-17T16:27:45.267Z,1673972865.267 [CBIT](DEBUG): Deactivating GF circuits.
2023-01-17T16:27:45.267Z,1673972865.267 [CBIT](DEBUG): Deactivating emergency mode.
2023-01-17T16:27:45.303Z,1673972865.303 [CBIT](DEBUG): Backplane powered.
2023-01-17T16:27:45.304Z,1673972865.304 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-01-17T16:27:45.318Z,1673972865.318 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-17T16:27:45.356Z,1673972865.356 [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-01-17T16:27:45.356Z,1673972865.356 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-01-17T16:27:45.431Z,1673972865.431 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-01-17T16:27:45.432Z,1673972865.432 [Default:A.Wait](DEBUG): Construct Wait.
2023-01-17T16:27:45.434Z,1673972865.434 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-01-17T16:27:45.474Z,1673972865.474 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-01-17T16:27:45.497Z,1673972865.497 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-01-17T16:27:45.502Z,1673972865.502 [Default:E.Execute](DEBUG): Construct Execute.
2023-01-17T16:27:45.522Z,1673972865.522 [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-01-17T16:27:45.526Z,1673972865.526 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-01-17T16:27:45.549Z,1673972865.549 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-01-17T16:27:45.612Z,1673972865.612 [Radio_Surface](INFO): Powering up
2023-01-17T16:27:45.628Z,1673972865.628 [Depth_Keller](ERROR): Pressure reading out of range: 915.616455 decibar
2023-01-17T16:27:45.629Z,1673972865.629 [Power24vConverter](INFO): Powering up.
2023-01-17T16:27:45.630Z,1673972865.630 [CANONSampler](INFO): Powering down
2023-01-17T16:27:45.634Z,1673972865.634 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-01-17T16:27:45.657Z,1673972865.657 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:27:45.664Z,1673972865.664 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-01-17T16:27:45.665Z,1673972865.665 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:27:45.672Z,1673972865.672 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-01-17T16:27:45.673Z,1673972865.673 [MassServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:27:45.680Z,1673972865.680 [MassServo](DEBUG): Initializing MassServo.
2023-01-17T16:27:45.681Z,1673972865.681 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:27:45.688Z,1673972865.688 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-17T16:27:45.689Z,1673972865.689 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:27:45.696Z,1673972865.696 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-01-17T16:27:46.671Z,1673972866.671 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2023-01-17T16:27:46.672Z,1673972866.672 [RudderServo](FAULT): Rudder failed to initialize
2023-01-17T16:27:46.672Z,1673972866.672 [RudderServo] Communications Fault, FailCount= 1
2023-01-17T16:27:46.672Z,1673972866.672 [RudderServo](ERROR): Communications Fault
2023-01-17T16:27:46.775Z,1673972866.775 [CBIT](ERROR): Communications Fault in component: RudderServo
2023-01-17T16:27:46.968Z,1673972866.968 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-01-17T16:27:46.969Z,1673972866.969 [RudderServo](INFO): Powering down
2023-01-17T16:27:47.320Z,1673972867.320 [WetLabsBB2FL](INFO): Powering down
2023-01-17T16:27:47.611Z,1673972867.611 [RudderServo](DEBUG): Initializing EZServoServo.
2023-01-17T16:27:47.732Z,1673972867.732 [RudderServo](DEBUG): Initializing RudderServo.
2023-01-17T16:27:47.736Z,1673972867.736 [CBIT](INFO): Clearing failed state for component RudderServo
2023-01-17T16:27:47.736Z,1673972867.736 [RudderServo] No Fault, FailCount= 1
2023-01-17T16:27:53.245Z,1673972873.245 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-01-17T16:28:13.857Z,1673972893.857 [NAL9602](INFO): Powering up NAL9602
2023-01-17T16:28:24.767Z,1673972904.767 [NAL9602](INFO): NAL9602 initialized
2023-01-17T16:28:37.710Z,1673972917.710 [SBIT](IMPORTANT): Beginning Startup BIT
2023-01-17T16:28:37.715Z,1673972917.715 [CBIT](IMPORTANT): Beginning ground fault scan
2023-01-17T16:28:48.666Z,1673972928.666 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000414
CHAN A1 (24V): 0.000606
CHAN A2 (12V): -0.001974
CHAN A3 (5V): -0.001295
CHAN B0 (3.3V): -0.000165
CHAN B1 (3.15aV): -0.000022
CHAN B2 (3.15bV): -0.000532
CHAN B3 (GND): -0.000354
OPEN: -0.000214
Full Scale: +/- 1 mA
2023-01-17T16:28:49.013Z,1673972929.013 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-01-17T16:29:03.545Z,1673972943.545 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:29:17.765Z,1673972957.765 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.762486
2023-01-17T16:29:17.766Z,1673972957.766 [SBIT](FAULT): Control surface position failure.
2023-01-17T16:29:18.492Z,1673972958.492 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:29:31.870Z,1673972971.870 [SBIT](CRITICAL): SBIT FAILED
2023-01-17T16:29:31.871Z,1673972971.871 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2023-01-17T16:29:31.872Z,1673972971.872 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): Express linearApproximation ThrusterServo.component_avgCurrent 10.000000 milliampere;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=176.310712 cubic_centimeter;

2023-01-17T16:29:31.873Z,1673972971.873 [SBIT](IMPORTANT): VerticalControl.massDefault=-8.249717 millimeter;

2023-01-17T16:29:31.875Z,1673972971.875 [CommandExec](FAULT): Scheduling is paused
2023-01-17T16:29:31.875Z,1673972971.875 [CBIT](INFO): Critical error at 20230117T162931
2023-01-17T16:29:31.876Z,1673972971.876 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-01-17T16:29:32.246Z,1673972972.246 [MissionManager](IMPORTANT): Started mission Startup
2023-01-17T16:29:32.246Z,1673972972.246 [Startup] Running Loop=1
2023-01-17T16:29:32.246Z,1673972972.246 [Startup](DEBUG): Aggregate::initialize Startup
2023-01-17T16:29:32.247Z,1673972972.247 [Startup:A.GoToSurface] Running Loop=1
2023-01-17T16:29:32.247Z,1673972972.247 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-01-17T16:29:32.248Z,1673972972.248 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-01-17T16:29:32.248Z,1673972972.248 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-01-17T16:29:32.249Z,1673972972.249 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-01-17T16:29:32.249Z,1673972972.249 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-01-17T16:29:32.249Z,1673972972.249 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-01-17T16:29:32.251Z,1673972972.251 [Startup:StartupSatComms] Running Loop=1
2023-01-17T16:29:32.251Z,1673972972.251 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-01-17T16:29:32.251Z,1673972972.251 [Startup:StartupSatComms:A] Running Loop=1
2023-01-17T16:29:32.662Z,1673972972.662 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-01-17T16:29:33.440Z,1673972973.440 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:29:48.792Z,1673972988.792 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-01-17T16:30:30.486Z,1673973030.486 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004959
2023-01-17T16:30:32.453Z,1673973032.453 [Startup:StartupSatComms:A](INFO): Timed out from 2023-01-17T16:29:32.3Z
2023-01-17T16:30:32.453Z,1673973032.453 [Startup:StartupSatComms:A] Stopped
2023-01-17T16:30:32.453Z,1673973032.453 [Startup:StartupSatComms:B] Running Loop=1
2023-01-17T16:30:32.863Z,1673973032.863 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-01-17T16:30:39.703Z,1673973039.703 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230117T161852/Courier0004.lzma
2023-01-17T16:30:40.705Z,1673973040.705 [DataOverHttps](INFO): Moved sent file to Logs/20230117T161852/Courier0004.lzma.bak
2023-01-17T16:30:40.706Z,1673973040.706 [DataOverHttps](INFO): SBD MOMSN=17401412
2023-01-17T16:30:45.505Z,1673973045.505 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-01-17T16:30:45.505Z,1673973045.505 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-01-17T16:30:45.528Z,1673973045.528 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-01-17T16:30:45.941Z,1673973045.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-01-17T16:30:45.941Z,1673973045.941 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-01-17T16:30:56.471Z,1673973056.471 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20230117T162735/Courier0000.lzma
2023-01-17T16:30:57.473Z,1673973057.473 [DataOverHttps](INFO): Moved sent file to Logs/20230117T162735/Courier0000.lzma.bak
2023-01-17T16:30:57.473Z,1673973057.473 [DataOverHttps](INFO): SBD MOMSN=17401414
2023-01-17T16:31:12.865Z,1673973072.865 [DataOverHttps](INFO): Sending 1030 bytes from file Logs/20230117T161852/Express0001.lzma
2023-01-17T16:31:13.865Z,1673973073.865 [DataOverHttps](INFO): Moved sent file to Logs/20230117T161852/Express0001.lzma.bak
2023-01-17T16:31:13.865Z,1673973073.865 [DataOverHttps](INFO): SBD MOMSN=17401418
2023-01-17T16:31:29.487Z,1673973089.487 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20230117T161852/Express0005.lzma
2023-01-17T16:31:30.489Z,1673973090.489 [DataOverHttps](INFO): Moved sent file to Logs/20230117T161852/Express0005.lzma.bak
2023-01-17T16:31:30.489Z,1673973090.489 [DataOverHttps](INFO): SBD MOMSN=17401448
2023-01-17T16:31:32.789Z,1673973092.789 [Startup:StartupSatComms:B](INFO): Timed out from 2023-01-17T16:30:32.5Z
2023-01-17T16:31:32.789Z,1673973092.789 [Startup:StartupSatComms:B] Stopped
2023-01-17T16:31:32.789Z,1673973092.789 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-01-17T16:31:32.789Z,1673973092.789 [Startup:StartupSatComms] Stopped
2023-01-17T16:31:32.789Z,1673973092.789 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-01-17T16:31:32.790Z,1673973092.790 [Startup](INFO): Completed Startup
2023-01-17T16:31:32.790Z,1673973092.790 [MissionManager](INFO): Startup is completed.
2023-01-17T16:31:32.791Z,1673973092.791 [MissionManager](INFO): Uninitializing Mission Startup
2023-01-17T16:31:32.791Z,1673973092.791 [Startup] Stopped
2023-01-17T16:31:32.791Z,1673973092.791 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-01-17T16:31:32.791Z,1673973092.791 [Startup:A.GoToSurface] Stopped
2023-01-17T16:31:32.791Z,1673973092.791 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-01-17T16:31:33.178Z,1673973093.178 [MissionManager](IMPORTANT): Started mission Default
2023-01-17T16:31:33.178Z,1673973093.178 [Default] Running Loop=1
2023-01-17T16:31:33.178Z,1673973093.178 [Default](DEBUG): Aggregate::initialize Default
2023-01-17T16:31:33.178Z,1673973093.178 [Default:B.GoToSurface] Running Loop=1
2023-01-17T16:31:33.178Z,1673973093.178 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-01-17T16:31:33.178Z,1673973093.178 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-01-17T16:31:33.179Z,1673973093.179 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-01-17T16:31:33.179Z,1673973093.179 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-01-17T16:31:33.179Z,1673973093.179 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-01-17T16:31:33.180Z,1673973093.180 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-01-17T16:31:33.180Z,1673973093.180 [Default:A.Wait] Running Loop=1
2023-01-17T16:31:33.180Z,1673973093.180 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-01-17T16:31:46.022Z,1673973106.022 [DataOverHttps](INFO): Sending 1115 bytes from file Logs/20230117T162735/Express0001.lzma
2023-01-17T16:31:46.511Z,1673973106.511 [Default:A.Wait](INFO): Done Waiting.
2023-01-17T16:31:46.511Z,1673973106.511 [Default:A.Wait] Stopped
2023-01-17T16:31:46.511Z,1673973106.511 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-01-17T16:31:46.927Z,1673973106.927 [Default:CheckIn] Running Loop=1
2023-01-17T16:31:46.927Z,1673973106.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-01-17T16:31:46.928Z,1673973106.928 [Default:CheckIn:Read_GPS] Running Loop=1
2023-01-17T16:31:47.021Z,1673973107.021 [DataOverHttps](INFO): Moved sent file to Logs/20230117T162735/Express0001.lzma.bak
2023-01-17T16:31:47.021Z,1673973107.021 [DataOverHttps](INFO): SBD MOMSN=17401453
2023-01-17T16:31:47.318Z,1673973107.318 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-01-17T16:32:56.337Z,1673973176.337 [CommandExec](IMPORTANT): got command restart application
2023-01-17T16:32:57.340Z,1673973177.340 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:32:57.340Z,1673973177.340 [CommandExec](INFO): Uninitializing the command executive.
2023-01-17T16:32:57.340Z,1673973177.340 [CommandExec](INFO): Uninitializing the command scheduler.
2023-01-17T16:32:57.340Z,1673973177.340 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:57.363Z,1673973177.363 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-01-17T16:32:57.364Z,1673973177.364 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-01-17T16:32:57.364Z,1673973177.364 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:57.364Z,1673973177.364 [NavChartDb](INFO): Join timeout helper Thread ID is 1770
2023-01-17T16:32:57.747Z,1673973177.747 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:32:57.748Z,1673973177.748 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:57.763Z,1673973177.763 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-01-17T16:32:57.763Z,1673973177.763 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:57.764Z,1673973177.764 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1771
2023-01-17T16:32:57.836Z,1673973177.836 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:32:57.836Z,1673973177.836 [WetLabsBB2FL](INFO): Powering down
2023-01-17T16:32:57.837Z,1673973177.837 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:57.843Z,1673973177.843 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-01-17T16:32:57.843Z,1673973177.843 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:57.844Z,1673973177.844 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1772
2023-01-17T16:32:58.508Z,1673973178.508 [CTD_Seabird](INFO): Powering down
2023-01-17T16:32:58.519Z,1673973178.519 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:32:58.520Z,1673973178.520 [CTD_Seabird](INFO): Powering down
2023-01-17T16:32:58.531Z,1673973178.531 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:58.535Z,1673973178.535 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-01-17T16:32:58.536Z,1673973178.536 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:58.536Z,1673973178.536 [Radio_Surface](INFO): Join timeout helper Thread ID is 1773
2023-01-17T16:32:58.788Z,1673973178.788 [Radio_Surface](INFO): Powering down
2023-01-17T16:32:58.788Z,1673973178.788 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:32:58.789Z,1673973178.789 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:58.800Z,1673973178.800 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-01-17T16:32:58.800Z,1673973178.800 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-01-17T16:32:58.800Z,1673973178.800 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:32:58.801Z,1673973178.801 [Onboard](INFO): Join timeout helper Thread ID is 1774
2023-01-17T16:33:00.444Z,1673973180.444 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:33:00.444Z,1673973180.444 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.453Z,1673973180.453 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-01-17T16:33:00.453Z,1673973180.453 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.454Z,1673973180.454 [DataOverHttps](INFO): Join timeout helper Thread ID is 1775
2023-01-17T16:33:00.808Z,1673973180.808 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:33:00.808Z,1673973180.808 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.828Z,1673973180.828 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-01-17T16:33:00.828Z,1673973180.828 [logger ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.829Z,1673973180.829 [logger](INFO): Join timeout helper Thread ID is 1776
2023-01-17T16:33:00.844Z,1673973180.844 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:33:00.844Z,1673973180.844 [logger ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.849Z,1673973180.849 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-01-17T16:33:00.849Z,1673973180.849 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.850Z,1673973180.850 [CommandLine](INFO): Join timeout helper Thread ID is 1777
2023-01-17T16:33:00.940Z,1673973180.940 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:33:00.940Z,1673973180.940 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.948Z,1673973180.948 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-01-17T16:33:00.948Z,1673973180.948 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.949Z,1673973180.949 [CommandExec](INFO): Join timeout helper Thread ID is 1778
2023-01-17T16:33:00.950Z,1673973180.950 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-01-17T16:33:00.950Z,1673973180.950 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:00.950Z,1673973180.950 [controlThread](INFO): Join timeout helper Thread ID is 1779
2023-01-17T16:33:01.223Z,1673973181.223 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-01-17T16:33:01.224Z,1673973181.224 [controlThread](DEBUG): Uninitializing ControlThread
2023-01-17T16:33:01.224Z,1673973181.224 [AHRS_M2](INFO): Powering down
2023-01-17T16:33:01.297Z,1673973181.297 [NAL9602](INFO): Powering down
2023-01-17T16:33:01.299Z,1673973181.299 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-01-17T16:33:01.300Z,1673973181.300 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-01-17T16:33:01.301Z,1673973181.301 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-01-17T16:33:01.301Z,1673973181.301 [MissionManager](INFO): Uninitializing Mission Default
2023-01-17T16:33:01.301Z,1673973181.301 [Default] Stopped
2023-01-17T16:33:01.301Z,1673973181.301 [Default](DEBUG): Aggregate::uninitialize Default
2023-01-17T16:33:01.301Z,1673973181.301 [Default:B.GoToSurface] Stopped
2023-01-17T16:33:01.301Z,1673973181.301 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-01-17T16:33:01.301Z,1673973181.301 [Default:CheckIn] Stopped
2023-01-17T16:33:01.302Z,1673973181.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-01-17T16:33:01.302Z,1673973181.302 [Default:CheckIn:Read_GPS] Stopped
2023-01-17T16:33:01.304Z,1673973181.304 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-01-17T16:33:01.305Z,1673973181.305 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-01-17T16:33:01.305Z,1673973181.305 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-01-17T16:33:01.305Z,1673973181.305 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-01-17T16:33:01.305Z,1673973181.305 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-01-17T16:33:01.306Z,1673973181.306 [BuoyancyServo](INFO): Powering down
2023-01-17T16:33:01.320Z,1673973181.320 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-01-17T16:33:01.320Z,1673973181.320 [ElevatorServo](INFO): Powering down
2023-01-17T16:33:01.320Z,1673973181.320 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-01-17T16:33:01.321Z,1673973181.321 [MassServo](INFO): Powering down
2023-01-17T16:33:01.321Z,1673973181.321 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-01-17T16:33:01.322Z,1673973181.322 [RudderServo](INFO): Powering down
2023-01-17T16:33:01.322Z,1673973181.322 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-01-17T16:33:01.322Z,1673973181.322 [ThrusterServo](INFO): Powering down
2023-01-17T16:33:01.323Z,1673973181.323 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-01-17T16:33:01.324Z,1673973181.324 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-01-17T16:33:01.324Z,1673973181.324 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-01-17T16:33:01.324Z,1673973181.324 [CBIT](DEBUG): Powering off loads.
2023-01-17T16:33:01.335Z,1673973181.335 [CBIT](DEBUG): Disabling WDT.
2023-01-17T16:33:01.347Z,1673973181.347 [CBIT](DEBUG): Opening all GF detection circuits.
2023-01-17T16:33:01.348Z,1673973181.348 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.405Z,1673973181.405 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.408Z,1673973181.408 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.413Z,1673973181.413 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.485Z,1673973181.485 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.488Z,1673973181.488 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.528Z,1673973181.528 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-01-17T16:33:01.589Z,1673973181.589 [logger ThreadHandler](INFO): Thread cancelled.