2022-04-08T22:04:47.330Z,1649455487.330 [Supervisor](DEBUG): Initializing supervisor.
2022-04-08T22:04:47.335Z,1649455487.335 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-04-08T22:04:47.335Z,1649455487.335 [SyncHandler](INFO): Protected caller Thread ID is 4463
2022-04-08T22:04:47.335Z,1649455487.335 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-04-08T22:04:47.336Z,1649455487.336 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-04-08T22:04:47.337Z,1649455487.337 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4464
2022-04-08T22:04:47.341Z,1649455487.341 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-04-08T22:04:47.359Z,1649455487.359 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-04-08T22:04:47.360Z,1649455487.360 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-04-08T22:04:47.361Z,1649455487.361 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4465
2022-04-08T22:04:47.365Z,1649455487.365 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-04-08T22:04:47.367Z,1649455487.367 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-04-08T22:04:47.368Z,1649455487.368 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4466
2022-04-08T22:04:47.370Z,1649455487.370 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-04-08T22:04:47.371Z,1649455487.371 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-04-08T22:04:47.371Z,1649455487.371 [logger ThreadHandler](INFO): Protected caller Thread ID is 4467
2022-04-08T22:04:47.375Z,1649455487.375 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-04-08T22:04:47.376Z,1649455487.376 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-04-08T22:04:47.381Z,1649455487.381 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-04-08T22:04:47.670Z,1649455487.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-04-08T22:04:47.671Z,1649455487.671 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-04-08T22:04:47.752Z,1649455487.752 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-04-08T22:04:48.252Z,1649455488.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-04-08T22:04:48.253Z,1649455488.253 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-04-08T22:04:48.572Z,1649455488.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-04-08T22:04:48.573Z,1649455488.573 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-04-08T22:04:48.668Z,1649455488.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-04-08T22:04:48.669Z,1649455488.669 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-04-08T22:04:49.029Z,1649455489.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-04-08T22:04:49.031Z,1649455489.031 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-04-08T22:04:49.232Z,1649455489.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-04-08T22:04:49.233Z,1649455489.233 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-04-08T22:04:49.682Z,1649455489.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-04-08T22:04:49.682Z,1649455489.682 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-04-08T22:04:49.807Z,1649455489.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-04-08T22:04:49.809Z,1649455489.809 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-04-08T22:04:49.889Z,1649455489.889 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-04-08T22:04:50.624Z,1649455490.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-04-08T22:04:50.625Z,1649455490.625 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-04-08T22:04:51.091Z,1649455491.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-04-08T22:04:51.092Z,1649455491.092 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-04-08T22:04:51.481Z,1649455491.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-04-08T22:04:51.482Z,1649455491.482 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-04-08T22:04:51.592Z,1649455491.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-04-08T22:04:51.594Z,1649455491.594 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-04-08T22:04:51.743Z,1649455491.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-04-08T22:04:51.745Z,1649455491.745 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-04-08T22:04:51.965Z,1649455491.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-04-08T22:04:51.967Z,1649455491.967 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-04-08T22:04:51.971Z,1649455491.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-04-08T22:04:52.060Z,1649455492.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-04-08T22:04:52.144Z,1649455492.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-04-08T22:04:52.245Z,1649455492.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-04-08T22:04:52.326Z,1649455492.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-04-08T22:04:52.421Z,1649455492.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-04-08T22:04:52.521Z,1649455492.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-04-08T22:04:52.696Z,1649455492.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-04-08T22:04:52.772Z,1649455492.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-04-08T22:04:52.915Z,1649455492.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-04-08T22:04:53.048Z,1649455493.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-04-08T22:04:53.161Z,1649455493.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-04-08T22:04:53.421Z,1649455493.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-04-08T22:04:53.422Z,1649455493.422 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2022-04-08T22:04:53.423Z,1649455493.423 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-04-08T22:04:53.435Z,1649455493.435 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-04-08T22:04:53.508Z,1649455493.508 [VerticalControl](DEBUG): Construct VerticalControl.
2022-04-08T22:04:53.570Z,1649455493.570 [VerticalControl] Loaded
2022-04-08T22:04:53.571Z,1649455493.571 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-04-08T22:04:53.573Z,1649455493.573 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-04-08T22:04:53.612Z,1649455493.612 [HorizontalControl] Loaded
2022-04-08T22:04:53.612Z,1649455493.612 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-04-08T22:04:53.615Z,1649455493.615 [SpeedControl](DEBUG): Construct SpeedControl.
2022-04-08T22:04:53.618Z,1649455493.618 [SpeedControl] Loaded
2022-04-08T22:04:53.618Z,1649455493.618 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-04-08T22:04:53.621Z,1649455493.621 [LoopControl](DEBUG): Construct LoopControl.
2022-04-08T22:04:53.621Z,1649455493.621 [LoopControl] Loaded
2022-04-08T22:04:53.621Z,1649455493.621 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-04-08T22:04:53.622Z,1649455493.622 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-04-08T22:04:53.622Z,1649455493.622 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-04-08T22:04:53.638Z,1649455493.638 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-04-08T22:04:53.639Z,1649455493.639 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-04-08T22:04:53.770Z,1649455493.770 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-04-08T22:04:53.771Z,1649455493.771 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-04-08T22:04:53.926Z,1649455493.926 [BuoyancyServo] Loaded
2022-04-08T22:04:53.927Z,1649455493.927 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-04-08T22:04:53.946Z,1649455493.946 [ElevatorServo] Loaded
2022-04-08T22:04:53.947Z,1649455493.947 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-04-08T22:04:53.965Z,1649455493.965 [MassServo] Loaded
2022-04-08T22:04:53.965Z,1649455493.965 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-04-08T22:04:53.983Z,1649455493.983 [RudderServo] Loaded
2022-04-08T22:04:53.983Z,1649455493.983 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-04-08T22:04:54.001Z,1649455494.001 [ThrusterServo] Loaded
2022-04-08T22:04:54.001Z,1649455494.001 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-04-08T22:04:54.002Z,1649455494.002 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-04-08T22:04:54.002Z,1649455494.002 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-04-08T22:04:54.104Z,1649455494.104 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-04-08T22:04:54.105Z,1649455494.105 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-04-08T22:04:54.118Z,1649455494.118 [NavChart] Loaded
2022-04-08T22:04:54.118Z,1649455494.118 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-04-08T22:04:54.124Z,1649455494.124 [UniversalFixResidualReporter] Loaded
2022-04-08T22:04:54.124Z,1649455494.124 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-04-08T22:04:54.125Z,1649455494.125 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-04-08T22:04:54.125Z,1649455494.125 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-04-08T22:04:54.209Z,1649455494.209 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-04-08T22:04:54.209Z,1649455494.209 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-04-08T22:04:54.869Z,1649455494.869 [AHRS_M2] Loaded
2022-04-08T22:04:54.869Z,1649455494.869 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-04-08T22:04:55.678Z,1649455495.678 [BPC1] Loaded
2022-04-08T22:04:55.679Z,1649455495.679 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-04-08T22:04:55.925Z,1649455495.925 [DataOverHttps] Loaded
2022-04-08T22:04:55.926Z,1649455495.926 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-04-08T22:04:55.927Z,1649455495.927 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2022-04-08T22:04:55.927Z,1649455495.927 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4553
2022-04-08T22:04:55.947Z,1649455495.947 [Depth_Keller] Loaded
2022-04-08T22:04:55.948Z,1649455495.948 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-04-08T22:04:55.952Z,1649455495.952 [DropWeight] Loaded
2022-04-08T22:04:55.953Z,1649455495.953 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-04-08T22:04:56.014Z,1649455496.014 [NAL9602] Loaded
2022-04-08T22:04:56.014Z,1649455496.014 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-04-08T22:04:56.044Z,1649455496.044 [Onboard] Loaded
2022-04-08T22:04:56.044Z,1649455496.044 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-04-08T22:04:56.045Z,1649455496.045 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2022-04-08T22:04:56.045Z,1649455496.045 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4554
2022-04-08T22:04:56.059Z,1649455496.059 [Power24vConverter] Loaded
2022-04-08T22:04:56.059Z,1649455496.059 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-04-08T22:04:56.072Z,1649455496.072 [Radio_Surface] Loaded
2022-04-08T22:04:56.072Z,1649455496.072 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-04-08T22:04:56.073Z,1649455496.073 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2022-04-08T22:04:56.074Z,1649455496.074 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4555
2022-04-08T22:04:56.075Z,1649455496.075 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-04-08T22:04:56.075Z,1649455496.075 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-04-08T22:04:56.223Z,1649455496.223 [DepthRateCalculator] Loaded
2022-04-08T22:04:56.223Z,1649455496.223 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-04-08T22:04:56.228Z,1649455496.228 [PitchRateCalculator] Loaded
2022-04-08T22:04:56.228Z,1649455496.228 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-04-08T22:04:56.238Z,1649455496.238 [SpeedCalculator] Loaded
2022-04-08T22:04:56.238Z,1649455496.238 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-04-08T22:04:56.252Z,1649455496.252 [TempGradientCalculator] Loaded
2022-04-08T22:04:56.252Z,1649455496.252 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-04-08T22:04:56.257Z,1649455496.257 [YawRateCalculator] Loaded
2022-04-08T22:04:56.257Z,1649455496.257 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-04-08T22:04:56.290Z,1649455496.290 [ElevatorOffsetCalculator] Loaded
2022-04-08T22:04:56.290Z,1649455496.290 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-04-08T22:04:56.290Z,1649455496.290 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-04-08T22:04:56.291Z,1649455496.291 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-04-08T22:04:57.089Z,1649455497.089 [CANONSampler] Loaded
2022-04-08T22:04:57.090Z,1649455497.090 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-04-08T22:04:57.149Z,1649455497.149 [CTD_Seabird] Loaded
2022-04-08T22:04:57.149Z,1649455497.149 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-04-08T22:04:57.150Z,1649455497.150 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-04-08T22:04:57.151Z,1649455497.151 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4556
2022-04-08T22:04:57.171Z,1649455497.171 [PAR_Licor] Loaded
2022-04-08T22:04:57.171Z,1649455497.171 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-04-08T22:04:57.205Z,1649455497.205 [WetLabsBB2FL] Loaded
2022-04-08T22:04:57.205Z,1649455497.205 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-04-08T22:04:57.206Z,1649455497.206 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2022-04-08T22:04:57.206Z,1649455497.206 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4557
2022-04-08T22:04:57.207Z,1649455497.207 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-04-08T22:04:57.208Z,1649455497.208 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-04-08T22:04:57.250Z,1649455497.250 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-04-08T22:04:57.251Z,1649455497.251 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-04-08T22:04:57.630Z,1649455497.630 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-04-08T22:04:57.631Z,1649455497.631 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-04-08T22:04:57.808Z,1649455497.808 [SBIT](DEBUG): Construct Startup Built In Test.
2022-04-08T22:04:57.817Z,1649455497.817 [SBIT] Loaded
2022-04-08T22:04:57.818Z,1649455497.818 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-04-08T22:04:57.820Z,1649455497.820 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-04-08T22:04:57.834Z,1649455497.834 [IBIT] Loaded
2022-04-08T22:04:57.834Z,1649455497.834 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-04-08T22:04:57.840Z,1649455497.840 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-04-08T22:04:57.943Z,1649455497.943 [CBIT] Loaded
2022-04-08T22:04:57.943Z,1649455497.943 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-04-08T22:04:57.944Z,1649455497.944 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-04-08T22:04:57.944Z,1649455497.944 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-04-08T22:04:58.064Z,1649455498.064 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-04-08T22:04:58.070Z,1649455498.070 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-04-08T22:04:58.073Z,1649455498.073 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-04-08T22:04:58.084Z,1649455498.084 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-04-08T22:04:58.085Z,1649455498.085 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B414E0
2022-04-08T22:04:58.086Z,1649455498.086 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4558
2022-04-08T22:04:58.092Z,1649455498.092 [Supervisor](INFO): Main Thread ID is 4462
2022-04-08T22:04:58.092Z,1649455498.092 [Supervisor](DEBUG): Running supervisor.
2022-04-08T22:04:58.092Z,1649455498.092 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4559
2022-04-08T22:04:58.093Z,1649455498.093 [CommandExec](INFO): Initializing the command executive.
2022-04-08T22:04:58.095Z,1649455498.095 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4560
2022-04-08T22:04:58.097Z,1649455498.097 [controlThread ThreadHandler](INFO): Handler Thread ID is 4561
2022-04-08T22:04:58.097Z,1649455498.097 [controlThread](DEBUG): Initializing ControlThread
2022-04-08T22:04:58.098Z,1649455498.098 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-04-08T22:04:58.100Z,1649455498.100 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-04-08T22:04:58.101Z,1649455498.101 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-04-08T22:04:58.101Z,1649455498.101 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-04-08T22:04:58.103Z,1649455498.103 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-04-08T22:04:58.104Z,1649455498.104 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-04-08T22:04:58.108Z,1649455498.108 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-04-08T22:04:58.109Z,1649455498.109 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-04-08T22:04:58.109Z,1649455498.109 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-04-08T22:04:58.109Z,1649455498.109 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-04-08T22:04:58.110Z,1649455498.110 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-04-08T22:04:58.110Z,1649455498.110 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-04-08T22:04:58.115Z,1649455498.115 [SBIT](INFO): Initialize SBIT Component.
2022-04-08T22:04:58.115Z,1649455498.115 [SBIT](IMPORTANT): git: 2022-01-05
2022-04-08T22:04:58.115Z,1649455498.115 [SBIT](INFO): git hash: 4648efb7c0362dd4a6331fa887af857d1a6abffc
2022-04-08T22:04:58.116Z,1649455498.116 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-04-08T22:04:58.117Z,1649455498.117 [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
2022-04-08T22:04:58.118Z,1649455498.118 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2022-04-08T22:04:58.119Z,1649455498.119 [IBIT](INFO): Initialize IBIT Component.
2022-04-08T22:04:58.120Z,1649455498.120 [CBIT](DEBUG): Initialize CBIT Component.
2022-04-08T22:04:58.121Z,1649455498.121 [logger ThreadHandler](INFO): Handler Thread ID is 4562
2022-04-08T22:04:58.134Z,1649455498.134 [CBIT](DEBUG): Initialized mux pins.
2022-04-08T22:04:58.135Z,1649455498.135 [CBIT](DEBUG): Initializing the watchdog timer.
2022-04-08T22:04:58.139Z,1649455498.139 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4563
2022-04-08T22:04:58.140Z,1649455498.140 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-04-08T22:04:58.147Z,1649455498.147 [Onboard ThreadHandler](INFO): Handler Thread ID is 4564
2022-04-08T22:04:58.159Z,1649455498.159 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-04-08T22:04:58.159Z,1649455498.159 [CBIT](DEBUG): Initializing heartbeat.
2022-04-08T22:04:58.170Z,1649455498.170 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4565
2022-04-08T22:04:58.184Z,1649455498.184 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4566
2022-04-08T22:04:58.184Z,1649455498.184 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-04-08T22:04:58.188Z,1649455498.188 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4568
2022-04-08T22:04:58.191Z,1649455498.191 [WetLabsBB2FL](INFO): Powering up
2022-04-08T22:04:58.192Z,1649455498.192 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4570
2022-04-08T22:04:58.201Z,1649455498.201 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-04-08T22:04:58.201Z,1649455498.201 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-04-08T22:04:58.202Z,1649455498.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-04-08T22:04:58.202Z,1649455498.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-04-08T22:04:58.202Z,1649455498.202 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-04-08T22:04:58.202Z,1649455498.202 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-04-08T22:04:58.203Z,1649455498.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-04-08T22:04:58.203Z,1649455498.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-04-08T22:04:58.203Z,1649455498.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-04-08T22:04:58.203Z,1649455498.203 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-04-08T22:04:58.203Z,1649455498.203 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-04-08T22:04:58.204Z,1649455498.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-04-08T22:04:58.204Z,1649455498.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-04-08T22:04:58.204Z,1649455498.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-04-08T22:04:58.204Z,1649455498.204 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-04-08T22:04:58.204Z,1649455498.204 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-04-08T22:04:58.230Z,1649455498.230 [CBIT](DEBUG): Deactivating GF circuits.
2022-04-08T22:04:58.230Z,1649455498.230 [CBIT](DEBUG): Deactivating emergency mode.
2022-04-08T22:04:58.266Z,1649455498.266 [CBIT](DEBUG): Backplane powered.
2022-04-08T22:04:58.267Z,1649455498.267 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-04-08T22:04:58.303Z,1649455498.303 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-08T22:04:58.331Z,1649455498.331 [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>


2022-04-08T22:04:58.332Z,1649455498.332 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-04-08T22:04:58.405Z,1649455498.405 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-04-08T22:04:58.422Z,1649455498.422 [Default:A.Wait](DEBUG): Construct Wait.
2022-04-08T22:04:58.425Z,1649455498.425 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-04-08T22:04:58.458Z,1649455498.458 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-04-08T22:04:58.482Z,1649455498.482 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-04-08T22:04:58.513Z,1649455498.513 [Default:E.Execute](DEBUG): Construct Execute.
2022-04-08T22:04:58.516Z,1649455498.516 [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="P2H">
                <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>


2022-04-08T22:04:58.526Z,1649455498.526 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-04-08T22:04:58.539Z,1649455498.539 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-04-08T22:04:58.586Z,1649455498.586 [Radio_Surface](INFO): Powering up
2022-04-08T22:04:58.623Z,1649455498.623 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2022-04-08T22:04:58.624Z,1649455498.624 [Power24vConverter](INFO): Powering up.
2022-04-08T22:04:58.625Z,1649455498.625 [CANONSampler](INFO): Powering down
2022-04-08T22:04:58.691Z,1649455498.691 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-04-08T22:04:58.796Z,1649455498.796 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-04-08T22:04:58.806Z,1649455498.806 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-04-08T22:04:58.815Z,1649455498.815 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-04-08T22:04:58.823Z,1649455498.823 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-04-08T22:04:58.824Z,1649455498.824 [MassServo](DEBUG): Initializing EZServoServo.
2022-04-08T22:04:58.831Z,1649455498.831 [MassServo](DEBUG): Initializing MassServo.
2022-04-08T22:04:58.832Z,1649455498.832 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-08T22:04:58.843Z,1649455498.843 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-08T22:04:58.844Z,1649455498.844 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-04-08T22:04:58.851Z,1649455498.851 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-04-08T22:04:58.976Z,1649455498.976 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-04-08T22:04:58.976Z,1649455498.976 [DropWeight] Hardware Fault, FailCount= 1
2022-04-08T22:04:58.976Z,1649455498.976 [DropWeight](ERROR): Hardware Fault
2022-04-08T22:04:59.056Z,1649455499.056 [CommandExec](FAULT): Scheduling is paused
2022-04-08T22:04:59.057Z,1649455499.057 [CBIT](INFO): Critical error at 20220408T220458
2022-04-08T22:04:59.057Z,1649455499.057 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-04-08T22:04:59.060Z,1649455499.060 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-04-08T22:04:59.060Z,1649455499.060 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-04-08T22:04:59.667Z,1649455499.667 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-04-08T22:04:59.667Z,1649455499.667 [RudderServo](FAULT): Rudder failed to initialize
2022-04-08T22:04:59.667Z,1649455499.667 [RudderServo] Communications Fault, FailCount= 1
2022-04-08T22:04:59.667Z,1649455499.667 [RudderServo](ERROR): Communications Fault
2022-04-08T22:04:59.769Z,1649455499.769 [CBIT](INFO): Critical error at 20220408T220459
2022-04-08T22:04:59.771Z,1649455499.771 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-04-08T22:04:59.940Z,1649455499.940 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-08T22:04:59.951Z,1649455499.951 [RudderServo](INFO): Powering down
2022-04-08T22:05:00.298Z,1649455500.298 [WetLabsBB2FL](INFO): Powering down
2022-04-08T22:05:00.636Z,1649455500.636 [RudderServo](DEBUG): Initializing EZServoServo.
2022-04-08T22:05:00.755Z,1649455500.755 [RudderServo](DEBUG): Initializing RudderServo.
2022-04-08T22:05:00.760Z,1649455500.760 [CBIT](INFO): Clearing failed state for component RudderServo
2022-04-08T22:05:00.760Z,1649455500.760 [RudderServo] No Fault, FailCount= 1
2022-04-08T22:05:05.227Z,1649455505.227 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-04-08T22:05:11.997Z,1649455511.997 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005230
2022-04-08T22:05:26.052Z,1649455526.052 [NAL9602](INFO): Powering up NAL9602
2022-04-08T22:05:36.952Z,1649455536.952 [NAL9602](INFO): NAL9602 initialized
2022-04-08T22:06:05.363Z,1649455565.363 [CommandExec](IMPORTANT): got command gfscan
2022-04-08T22:06:05.712Z,1649455565.712 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-08T22:06:06.897Z,1649455566.897 [SBIT](IMPORTANT): Beginning Startup BIT
2022-04-08T22:06:16.581Z,1649455576.581 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-08T22:06:16.995Z,1649455576.995 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-08T22:06:28.108Z,1649455588.108 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-08T22:06:28.432Z,1649455588.432 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-08T22:06:29.920Z,1649455589.920 [CommandExec](IMPORTANT): got command get 
2022-04-08T22:06:29.921Z,1649455589.921 [CommandExec](FAULT): Incomplete syntax. Try: help get
2022-04-08T22:06:39.264Z,1649455599.264 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-08T22:06:39.264Z,1649455599.264 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-04-08T22:06:41.972Z,1649455601.972 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2022-04-08T22:06:41.972Z,1649455601.972 [CommandExec](IMPORTANT): Onboard.Pressure 69605.976562 Pa
2022-04-08T22:06:59.260Z,1649455619.260 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-04-08T22:06:59.260Z,1649455619.260 [CommandExec](IMPORTANT): Onboard.Humidity 9.547064 %
2022-04-08T22:07:01.059Z,1649455621.059 [SBIT](IMPORTANT): SBIT PASSED
2022-04-08T22:07:01.060Z,1649455621.060 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-04-08T22:07:01.061Z,1649455621.061 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-04-08T22:07:01.061Z,1649455621.061 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-04-08T22:07:01.061Z,1649455621.061 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-04-08T22:07:01.061Z,1649455621.061 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-04-08T22:07:01.061Z,1649455621.061 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234 cubic_centimeter;

2022-04-08T22:07:01.061Z,1649455621.061 [SBIT](IMPORTANT): VerticalControl.massDefault=-12 millimeter;

2022-04-08T22:07:01.406Z,1649455621.406 [MissionManager](IMPORTANT): Started mission Startup
2022-04-08T22:07:01.406Z,1649455621.406 [Startup] Running Loop=1
2022-04-08T22:07:01.407Z,1649455621.407 [Startup](DEBUG): Aggregate::initialize Startup
2022-04-08T22:07:01.407Z,1649455621.407 [Startup:A.GoToSurface] Running Loop=1
2022-04-08T22:07:01.407Z,1649455621.407 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-08T22:07:01.407Z,1649455621.407 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-08T22:07:01.408Z,1649455621.408 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-08T22:07:01.408Z,1649455621.408 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-08T22:07:01.409Z,1649455621.409 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-08T22:07:01.409Z,1649455621.409 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-08T22:07:01.411Z,1649455621.411 [Startup:StartupSatComms] Running Loop=1
2022-04-08T22:07:01.411Z,1649455621.411 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-04-08T22:07:01.411Z,1649455621.411 [Startup:StartupSatComms:A] Running Loop=1
2022-04-08T22:07:01.818Z,1649455621.818 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-04-08T22:07:07.335Z,1649455627.335 [CommandExec](IMPORTANT): got command gfscan
2022-04-08T22:07:07.555Z,1649455627.555 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-08T22:07:18.449Z,1649455638.449 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-08T22:07:18.865Z,1649455638.865 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-08T22:07:29.772Z,1649455649.772 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-04-08T22:07:30.175Z,1649455650.175 [CBIT](IMPORTANT): Beginning ground fault scan
2022-04-08T22:07:41.048Z,1649455661.048 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000313
CHAN A1 (24V): 0.000533
CHAN A2 (12V): -0.001904
CHAN A3 (5V): -0.001041
CHAN B0 (3.3V): 0.000054
CHAN B1 (3.15aV): 0.000172
CHAN B2 (3.15bV): -0.000345
CHAN B3 (GND): 0.000065
OPEN: -0.000424
Full Scale: +/- 1 mA
2022-04-08T22:07:58.381Z,1649455678.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-04-08T22:07:58.381Z,1649455678.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-08T22:07:58.392Z,1649455678.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-08T22:07:58.801Z,1649455678.801 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-08T22:07:58.801Z,1649455678.801 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-04-08T22:08:01.621Z,1649455681.621 [Startup:StartupSatComms:A](INFO): Timed out from 2022-04-08T22:07:01.4Z
2022-04-08T22:08:01.622Z,1649455681.622 [Startup:StartupSatComms:A] Stopped
2022-04-08T22:08:01.622Z,1649455681.622 [Startup:StartupSatComms:B] Running Loop=1
2022-04-08T22:08:01.960Z,1649455681.960 [CommandExec](IMPORTANT): got command show variable propomega
2022-04-08T22:08:02.052Z,1649455682.052 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-08T22:08:02.207Z,1649455682.207 [CommandExec](IMPORTANT): SpeedControl.propOmegaAction (radian_per_second)
2022-04-08T22:08:08.814Z,1649455688.814 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220317T195407/Courier0126.lzma
2022-04-08T22:08:09.817Z,1649455689.817 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Courier0126.lzma.bak
2022-04-08T22:08:09.817Z,1649455689.817 [DataOverHttps](INFO): SBD MOMSN=16633934
2022-04-08T22:08:25.395Z,1649455705.395 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20220408T220447/Courier0000.lzma
2022-04-08T22:08:26.396Z,1649455706.396 [DataOverHttps](INFO): Moved sent file to Logs/20220408T220447/Courier0000.lzma.bak
2022-04-08T22:08:26.396Z,1649455706.396 [DataOverHttps](INFO): SBD MOMSN=16633937
2022-04-08T22:08:36.343Z,1649455716.343 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2022-04-08T22:08:36.347Z,1649455716.347 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2022-04-08T22:08:36.446Z,1649455716.446 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-04-08T22:08:42.187Z,1649455722.187 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20220317T195407/Express0127.lzma
2022-04-08T22:08:43.189Z,1649455723.189 [DataOverHttps](INFO): Moved sent file to Logs/20220317T195407/Express0127.lzma.bak
2022-04-08T22:08:43.189Z,1649455723.189 [DataOverHttps](INFO): SBD MOMSN=16633943
2022-04-08T22:08:45.152Z,1649455725.152 [CommandExec](IMPORTANT): got command maintain clear
2022-04-08T22:08:45.356Z,1649455725.356 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-04-08T22:08:57.862Z,1649455737.862 [DataOverHttps](INFO): Sending 1055 bytes from file Logs/20220408T220447/Express0001.lzma
2022-04-08T22:08:58.860Z,1649455738.860 [DataOverHttps](INFO): Moved sent file to Logs/20220408T220447/Express0001.lzma.bak
2022-04-08T22:08:58.860Z,1649455738.860 [DataOverHttps](INFO): SBD MOMSN=16633947
2022-04-08T22:09:00.266Z,1649455740.266 [Startup:StartupSatComms:B] Stopped
2022-04-08T22:09:00.266Z,1649455740.266 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-04-08T22:09:00.266Z,1649455740.266 [Startup:StartupSatComms] Stopped
2022-04-08T22:09:00.266Z,1649455740.266 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-04-08T22:09:00.267Z,1649455740.267 [Startup](INFO): Completed Startup
2022-04-08T22:09:00.267Z,1649455740.267 [MissionManager](INFO): Startup is completed.
2022-04-08T22:09:00.267Z,1649455740.267 [MissionManager](INFO): Uninitializing Mission Startup
2022-04-08T22:09:00.268Z,1649455740.268 [Startup] Stopped
2022-04-08T22:09:00.268Z,1649455740.268 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-04-08T22:09:00.268Z,1649455740.268 [Startup:A.GoToSurface] Stopped
2022-04-08T22:09:00.268Z,1649455740.268 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-08T22:09:00.733Z,1649455740.733 [MissionManager](IMPORTANT): Started mission Default
2022-04-08T22:09:00.733Z,1649455740.733 [Default] Running Loop=1
2022-04-08T22:09:00.733Z,1649455740.733 [Default](DEBUG): Aggregate::initialize Default
2022-04-08T22:09:00.733Z,1649455740.733 [Default:B.GoToSurface] Running Loop=1
2022-04-08T22:09:00.733Z,1649455740.733 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-04-08T22:09:00.734Z,1649455740.734 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-04-08T22:09:00.734Z,1649455740.734 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-04-08T22:09:00.735Z,1649455740.735 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-04-08T22:09:00.735Z,1649455740.735 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-04-08T22:09:00.735Z,1649455740.735 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-04-08T22:09:00.736Z,1649455740.736 [Default:A.Wait] Running Loop=1
2022-04-08T22:09:00.736Z,1649455740.736 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-04-08T22:09:14.039Z,1649455754.039 [Default:A.Wait](INFO): Done Waiting.
2022-04-08T22:09:14.039Z,1649455754.039 [Default:A.Wait] Stopped
2022-04-08T22:09:14.039Z,1649455754.039 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-04-08T22:09:14.446Z,1649455754.446 [Default:CheckIn] Running Loop=1
2022-04-08T22:09:14.446Z,1649455754.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-04-08T22:09:14.446Z,1649455754.446 [Default:CheckIn:Read_GPS] Running Loop=1
2022-04-08T22:09:14.845Z,1649455754.845 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-04-08T22:09:28.427Z,1649455768.427 [CommandExec](IMPORTANT): got command restart
2022-04-08T22:09:28.428Z,1649455768.428 [CommandExec](FAULT): Incomplete syntax. Try: help restart
2022-04-08T22:10:22.800Z,1649455822.800 [CommandExec](IMPORTANT): got command show variable par
2022-04-08T22:10:22.837Z,1649455822.837 [CommandExec](IMPORTANT): PAR_Licor.loadControl (none)
2022-04-08T22:10:22.837Z,1649455822.837 [CommandExec](IMPORTANT): PAR_Licor.ad (none)
2022-04-08T22:10:22.837Z,1649455822.837 [CommandExec](IMPORTANT): PAR_Licor.adRes (bit)
2022-04-08T22:10:22.838Z,1649455822.838 [CommandExec](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2022-04-08T22:10:22.839Z,1649455822.839 [CommandExec](IMPORTANT): PAR_Licor.adVref (volt)
2022-04-08T22:10:22.853Z,1649455822.853 [CommandExec](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2022-04-08T22:10:22.853Z,1649455822.853 [CommandExec](IMPORTANT): PAR_Licor.simulateHardware (bool)
2022-04-08T22:10:22.854Z,1649455822.854 [CommandExec](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2022-04-08T22:10:22.854Z,1649455822.854 [CommandExec](IMPORTANT): PAR_Licor.darkCount (count)
2022-04-08T22:10:22.854Z,1649455822.854 [CommandExec](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2022-04-08T22:10:22.855Z,1649455822.855 [CommandExec](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2022-04-08T22:10:22.855Z,1649455822.855 [CommandExec](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2022-04-08T22:10:22.856Z,1649455822.856 [CommandExec](IMPORTANT): PAR_Licor.minValidPitch (degree)
2022-04-08T22:10:22.856Z,1649455822.856 [CommandExec](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2022-04-08T22:10:22.856Z,1649455822.856 [CommandExec](IMPORTANT): PAR_Licor.parCal (none)
2022-04-08T22:10:22.857Z,1649455822.857 [CommandExec](IMPORTANT): PAR_Licor.serial (none)
2022-04-08T22:10:22.956Z,1649455822.956 [CommandExec](IMPORTANT): PAR_Licor.enableBroadcast (bool)
2022-04-08T22:10:22.956Z,1649455822.956 [CommandExec](IMPORTANT): PAR_Licor.component_voltage (volt)
2022-04-08T22:10:22.956Z,1649455822.956 [CommandExec](IMPORTANT): PAR_Licor.component_avgVoltage (volt)
2022-04-08T22:10:22.957Z,1649455822.957 [CommandExec](IMPORTANT): PAR_Licor.component_current (milliampere)
2022-04-08T22:10:22.957Z,1649455822.957 [CommandExec](IMPORTANT): PAR_Licor.component_avgCurrent (milliampere)
2022-04-08T22:10:22.958Z,1649455822.958 [CommandExec](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2022-04-08T22:10:22.958Z,1649455822.958 [CommandExec](IMPORTANT): PAR_Licor.adcCount (count)
2022-04-08T22:10:40.448Z,1649455840.448 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-04-08T22:10:55.596Z,1649455855.596 [CommandExec](IMPORTANT): got command report touch PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water
2022-04-08T22:10:55.848Z,1649455855.848 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.508507 umol/s/m2
2022-04-08T22:10:56.258Z,1649455856.258 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.534451 umol/s/m2
2022-04-08T22:10:56.649Z,1649455856.649 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:10:57.051Z,1649455857.051 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.548865 umol/s/m2
2022-04-08T22:10:57.452Z,1649455857.452 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.494094 umol/s/m2
2022-04-08T22:10:57.841Z,1649455857.841 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.499860 umol/s/m2
2022-04-08T22:10:58.256Z,1649455858.256 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.494094 umol/s/m2
2022-04-08T22:10:58.664Z,1649455858.664 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:10:59.083Z,1649455859.083 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.548865 umol/s/m2
2022-04-08T22:10:59.449Z,1649455859.449 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-04-08T22:10:59.449Z,1649455859.449 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-08T22:10:59.461Z,1649455859.461 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-08T22:10:59.462Z,1649455859.462 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.502743 umol/s/m2
2022-04-08T22:10:59.871Z,1649455859.871 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-08T22:10:59.871Z,1649455859.871 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-04-08T22:10:59.872Z,1649455859.872 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.505626 umol/s/m2
2022-04-08T22:11:00.283Z,1649455860.283 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.540215 umol/s/m2
2022-04-08T22:11:00.740Z,1649455860.740 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.545982 umol/s/m2
2022-04-08T22:11:01.075Z,1649455861.075 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.468153 umol/s/m2
2022-04-08T22:11:01.573Z,1649455861.573 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.505626 umol/s/m2
2022-04-08T22:11:01.941Z,1649455861.941 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.494094 umol/s/m2
2022-04-08T22:11:02.324Z,1649455862.324 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.468153 umol/s/m2
2022-04-08T22:11:02.696Z,1649455862.696 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.505626 umol/s/m2
2022-04-08T22:11:03.097Z,1649455863.097 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.534451 umol/s/m2
2022-04-08T22:11:03.559Z,1649455863.559 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.485446 umol/s/m2
2022-04-08T22:11:03.944Z,1649455863.944 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.332667 umol/s/m2
2022-04-08T22:11:04.359Z,1649455864.359 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.565890 umol/s/m2
2022-04-08T22:11:04.752Z,1649455864.752 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 13.874061 umol/s/m2
2022-04-08T22:11:05.137Z,1649455865.137 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 12.331858 umol/s/m2
2022-04-08T22:11:05.580Z,1649455865.580 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 12.115662 umol/s/m2
2022-04-08T22:11:05.968Z,1649455865.968 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 11.245110 umol/s/m2
2022-04-08T22:11:06.372Z,1649455866.372 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 3.118997 umol/s/m2
2022-04-08T22:11:06.790Z,1649455866.790 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.028826 umol/s/m2
2022-04-08T22:11:07.152Z,1649455867.152 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.063418 umol/s/m2
2022-04-08T22:11:07.567Z,1649455867.567 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.014413 umol/s/m2
2022-04-08T22:11:07.953Z,1649455867.953 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.043239 umol/s/m2
2022-04-08T22:11:08.358Z,1649455868.358 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.040357 umol/s/m2
2022-04-08T22:11:08.764Z,1649455868.764 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.028826 umol/s/m2
2022-04-08T22:11:09.177Z,1649455869.177 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.028826 umol/s/m2
2022-04-08T22:11:09.552Z,1649455869.552 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.046122 umol/s/m2
2022-04-08T22:11:09.982Z,1649455869.982 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.051887 umol/s/m2
2022-04-08T22:11:10.379Z,1649455870.379 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.011530 umol/s/m2
2022-04-08T22:11:10.795Z,1649455870.795 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.028826 umol/s/m2
2022-04-08T22:11:11.209Z,1649455871.209 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.023061 umol/s/m2
2022-04-08T22:11:11.596Z,1649455871.596 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.046122 umol/s/m2
2022-04-08T22:11:11.997Z,1649455871.997 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.008648 umol/s/m2
2022-04-08T22:11:12.404Z,1649455872.404 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.017296 umol/s/m2
2022-04-08T22:11:12.784Z,1649455872.784 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.008648 umol/s/m2
2022-04-08T22:11:13.199Z,1649455873.199 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.017296 umol/s/m2
2022-04-08T22:11:13.613Z,1649455873.613 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.002883 umol/s/m2
2022-04-08T22:11:14.077Z,1649455874.077 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.017296 umol/s/m2
2022-04-08T22:11:14.483Z,1649455874.483 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.008648 umol/s/m2
2022-04-08T22:11:14.874Z,1649455874.874 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.005765 umol/s/m2
2022-04-08T22:11:15.274Z,1649455875.274 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.066300 umol/s/m2
2022-04-08T22:11:15.655Z,1649455875.655 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.054770 umol/s/m2
2022-04-08T22:11:16.054Z,1649455876.054 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.028826 umol/s/m2
2022-04-08T22:11:16.430Z,1649455876.430 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.040357 umol/s/m2
2022-04-08T22:11:16.852Z,1649455876.852 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water -0.014413 umol/s/m2
2022-04-08T22:11:17.267Z,1649455877.267 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 0.011530 umol/s/m2
2022-04-08T22:11:17.673Z,1649455877.673 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 13.568503 umol/s/m2
2022-04-08T22:11:18.043Z,1649455878.043 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 13.300420 umol/s/m2
2022-04-08T22:11:18.456Z,1649455878.456 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.810914 umol/s/m2
2022-04-08T22:11:18.872Z,1649455878.872 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 14.891626 umol/s/m2
2022-04-08T22:11:19.255Z,1649455879.255 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 13.905769 umol/s/m2
2022-04-08T22:11:19.703Z,1649455879.703 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.384554 umol/s/m2
2022-04-08T22:11:20.074Z,1649455880.074 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.476799 umol/s/m2
2022-04-08T22:11:20.492Z,1649455880.492 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.494094 umol/s/m2
2022-04-08T22:11:20.869Z,1649455880.869 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.482565 umol/s/m2
2022-04-08T22:11:21.280Z,1649455881.280 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.537333 umol/s/m2
2022-04-08T22:11:21.691Z,1649455881.691 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.528687 umol/s/m2
2022-04-08T22:11:22.106Z,1649455882.106 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.499860 umol/s/m2
2022-04-08T22:11:22.525Z,1649455882.525 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.525803 umol/s/m2
2022-04-08T22:11:22.936Z,1649455882.936 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.494094 umol/s/m2
2022-04-08T22:11:23.315Z,1649455883.315 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.520038 umol/s/m2
2022-04-08T22:11:23.720Z,1649455883.720 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.511391 umol/s/m2
2022-04-08T22:11:24.096Z,1649455884.096 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.540215 umol/s/m2
2022-04-08T22:11:24.524Z,1649455884.524 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.528687 umol/s/m2
2022-04-08T22:11:24.932Z,1649455884.932 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.531569 umol/s/m2
2022-04-08T22:11:25.338Z,1649455885.338 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.508507 umol/s/m2
2022-04-08T22:11:25.716Z,1649455885.716 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.548865 umol/s/m2
2022-04-08T22:11:26.124Z,1649455886.124 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.511391 umol/s/m2
2022-04-08T22:11:26.548Z,1649455886.548 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.485446 umol/s/m2
2022-04-08T22:11:26.959Z,1649455886.959 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.525803 umol/s/m2
2022-04-08T22:11:27.415Z,1649455887.415 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.517157 umol/s/m2
2022-04-08T22:11:27.742Z,1649455887.742 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:11:28.158Z,1649455888.158 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.534451 umol/s/m2
2022-04-08T22:11:28.639Z,1649455888.639 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.471034 umol/s/m2
2022-04-08T22:11:28.871Z,1649455888.871 [CommandExec](IMPORTANT): got command maintain clear
2022-04-08T22:11:28.959Z,1649455888.959 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.499860 umol/s/m2
2022-04-08T22:11:29.368Z,1649455889.368 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.482565 umol/s/m2
2022-04-08T22:11:29.792Z,1649455889.792 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.499860 umol/s/m2
2022-04-08T22:11:30.190Z,1649455890.190 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.517157 umol/s/m2
2022-04-08T22:11:30.600Z,1649455890.600 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.543098 umol/s/m2
2022-04-08T22:11:30.974Z,1649455890.974 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.583455 umol/s/m2
2022-04-08T22:11:31.395Z,1649455891.395 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:11:31.776Z,1649455891.776 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.540215 umol/s/m2
2022-04-08T22:11:32.180Z,1649455892.180 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.491212 umol/s/m2
2022-04-08T22:11:32.608Z,1649455892.608 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.505626 umol/s/m2
2022-04-08T22:11:33.021Z,1649455893.021 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.540215 umol/s/m2
2022-04-08T22:11:33.424Z,1649455893.424 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.569043 umol/s/m2
2022-04-08T22:11:33.795Z,1649455893.795 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.502743 umol/s/m2
2022-04-08T22:11:34.221Z,1649455894.221 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.520038 umol/s/m2
2022-04-08T22:11:34.632Z,1649455894.632 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:11:35.027Z,1649455895.027 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.482565 umol/s/m2
2022-04-08T22:11:35.459Z,1649455895.459 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.499860 umol/s/m2
2022-04-08T22:11:35.875Z,1649455895.875 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.491212 umol/s/m2
2022-04-08T22:11:36.271Z,1649455896.271 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.548865 umol/s/m2
2022-04-08T22:11:36.661Z,1649455896.661 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.505626 umol/s/m2
2022-04-08T22:11:37.064Z,1649455897.064 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:11:37.448Z,1649455897.448 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.514274 umol/s/m2
2022-04-08T22:11:37.842Z,1649455897.842 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.479682 umol/s/m2
2022-04-08T22:11:38.260Z,1649455898.260 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.496979 umol/s/m2
2022-04-08T22:11:38.676Z,1649455898.676 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.551746 umol/s/m2
2022-04-08T22:11:39.080Z,1649455899.080 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.465268 umol/s/m2
2022-04-08T22:11:39.291Z,1649455899.291 [CommandExec](IMPORTANT): got command retransmit
2022-04-08T22:11:39.291Z,1649455899.291 [CommandExec](FAULT): Incomplete syntax. Try: help retransmit
2022-04-08T22:11:39.482Z,1649455899.482 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.508507 umol/s/m2
2022-04-08T22:11:39.880Z,1649455899.880 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.566160 umol/s/m2
2022-04-08T22:11:40.259Z,1649455900.259 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.482565 umol/s/m2
2022-04-08T22:11:40.662Z,1649455900.662 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.540215 umol/s/m2
2022-04-08T22:11:41.132Z,1649455901.132 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.528687 umol/s/m2
2022-04-08T22:11:41.512Z,1649455901.512 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.502743 umol/s/m2
2022-04-08T22:11:41.909Z,1649455901.909 [Reporter](INFO): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 15.517157 umol/s/m2
2022-04-08T22:11:42.308Z,1649455902.308 [CommandExec](IMPORTANT): got command report clear
2022-04-08T22:11:47.363Z,1649455907.363 [CommandExec](IMPORTANT): got command 
2022-04-08T22:11:47.363Z,1649455907.363 [CommandExec](FAULT): Incomplete syntax. Try: help
2022-04-08T22:12:21.852Z,1649455941.852 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-04-08T22:12:21.855Z,1649455941.855 [BPC1](INFO): Received data from all battery sticks.
2022-04-08T22:13:25.975Z,1649456005.975 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15.000000 
2022-04-08T22:13:25.975Z,1649456005.975 [CommandExec](FAULT): Incomplete syntax. Try: help maintain
2022-04-08T22:13:32.571Z,1649456012.571 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15.000000 degree
2022-04-08T22:13:32.587Z,1649456012.587 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2022-04-08T22:13:32.611Z,1649456012.611 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-04-08T22:13:45.308Z,1649456025.308 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15.000000
2022-04-08T22:13:45.308Z,1649456025.308 [CommandExec](FAULT): Incomplete syntax. Try: help maintain
2022-04-08T22:13:55.939Z,1649456035.939 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15.000000 degree
2022-04-08T22:14:00.458Z,1649456040.458 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-04-08T22:14:00.458Z,1649456040.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-04-08T22:14:00.514Z,1649456040.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-04-08T22:14:00.883Z,1649456040.883 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-04-08T22:14:00.883Z,1649456040.883 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-04-08T22:14:12.011Z,1649456052.011 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0.000000 degree
2022-04-08T22:14:14.610Z,1649456054.610 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-04-08T22:09:14.4Z
2022-04-08T22:14:14.610Z,1649456054.610 [Default:CheckIn:Read_GPS] Stopped
2022-04-08T22:14:14.610Z,1649456054.610 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-04-08T22:14:15.003Z,1649456055.003 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-04-08T22:14:21.838Z,1649456061.838 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220408T220447/Courier0004.lzma
2022-04-08T22:14:22.840Z,1649456062.840 [DataOverHttps](INFO): Moved sent file to Logs/20220408T220447/Courier0004.lzma.bak
2022-04-08T22:14:22.840Z,1649456062.840 [DataOverHttps](INFO): SBD MOMSN=16634001
2022-04-08T22:14:38.490Z,1649456078.490 [DataOverHttps](INFO): Sending 851 bytes from file Logs/20220408T220447/Express0005.lzma
2022-04-08T22:14:39.488Z,1649456079.488 [DataOverHttps](INFO): Moved sent file to Logs/20220408T220447/Express0005.lzma.bak
2022-04-08T22:14:39.488Z,1649456079.488 [DataOverHttps](INFO): SBD MOMSN=16634003
2022-04-08T22:14:40.857Z,1649456080.857 [Default:CheckIn:Read_Iridium] Stopped
2022-04-08T22:14:40.857Z,1649456080.857 [Default:CheckIn:C.Wait] Running Loop=1
2022-04-08T22:14:40.857Z,1649456080.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-04-08T22:14:45.257Z,1649456085.257 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15.000000 degree
2022-04-08T22:14:45.260Z,1649456085.260 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2022-04-08T22:14:45.373Z,1649456085.373 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-04-08T22:14:57.643Z,1649456097.643 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15.000000 degree
2022-04-08T22:14:59.414Z,1649456099.414 [Power24vConverter](INFO): Powering down.
2022-04-08T22:15:15.453Z,1649456115.453 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0.000000 degree
2022-04-08T22:15:38.632Z,1649456138.632 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-04-08T22:15:38.632Z,1649456138.632 [NAL9602] Data Fault, FailCount= 1
2022-04-08T22:15:38.632Z,1649456138.632 [NAL9602](ERROR): Data Fault
2022-04-08T22:15:38.685Z,1649456138.685 [CBIT](ERROR): Data Fault in component: NAL9602
2022-04-08T22:15:39.036Z,1649456139.036 [NAL9602](INFO): Powering down
2022-04-08T22:15:39.865Z,1649456139.865 [CBIT](INFO): Clearing failed state for component NAL9602
2022-04-08T22:15:39.865Z,1649456139.865 [NAL9602] No Fault, FailCount= 1
2022-04-08T22:16:09.336Z,1649456169.336 [NAL9602](INFO): Powering up NAL9602
2022-04-08T22:16:11.875Z,1649456171.875 [CommandExec](IMPORTANT): got command quit
2022-04-08T22:16:12.879Z,1649456172.879 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:12.879Z,1649456172.879 [CommandExec](INFO): Uninitializing the command executive.
2022-04-08T22:16:12.879Z,1649456172.879 [CommandExec](INFO): Uninitializing the command scheduler.
2022-04-08T22:16:12.879Z,1649456172.879 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:12.954Z,1649456172.954 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-04-08T22:16:12.954Z,1649456172.954 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-04-08T22:16:12.955Z,1649456172.955 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:12.955Z,1649456172.955 [NavChartDb](INFO): Join timeout helper Thread ID is 4597
2022-04-08T22:16:13.119Z,1649456173.119 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:13.119Z,1649456173.119 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:13.138Z,1649456173.138 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-04-08T22:16:13.138Z,1649456173.138 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:13.139Z,1649456173.139 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4598
2022-04-08T22:16:13.330Z,1649456173.330 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:13.331Z,1649456173.331 [WetLabsBB2FL](INFO): Powering down
2022-04-08T22:16:13.332Z,1649456173.332 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:13.338Z,1649456173.338 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-04-08T22:16:13.338Z,1649456173.338 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:13.339Z,1649456173.339 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4599
2022-04-08T22:16:14.031Z,1649456174.031 [CTD_Seabird](INFO): Powering down
2022-04-08T22:16:14.042Z,1649456174.042 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:14.043Z,1649456174.043 [CTD_Seabird](INFO): Powering down
2022-04-08T22:16:14.054Z,1649456174.054 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:14.066Z,1649456174.066 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-04-08T22:16:14.066Z,1649456174.066 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:14.067Z,1649456174.067 [Radio_Surface](INFO): Join timeout helper Thread ID is 4600
2022-04-08T22:16:14.316Z,1649456174.316 [Radio_Surface](INFO): Powering down
2022-04-08T22:16:14.317Z,1649456174.317 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:14.317Z,1649456174.317 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:14.326Z,1649456174.326 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-04-08T22:16:14.327Z,1649456174.327 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:14.327Z,1649456174.327 [Onboard](INFO): Join timeout helper Thread ID is 4601
2022-04-08T22:16:18.739Z,1649456178.739 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:18.739Z,1649456178.739 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:18.751Z,1649456178.751 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-04-08T22:16:18.751Z,1649456178.751 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:18.752Z,1649456178.752 [DataOverHttps](INFO): Join timeout helper Thread ID is 4602
2022-04-08T22:16:19.318Z,1649456179.318 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:19.321Z,1649456179.321 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.323Z,1649456179.323 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-04-08T22:16:19.323Z,1649456179.323 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.324Z,1649456179.324 [logger](INFO): Join timeout helper Thread ID is 4603
2022-04-08T22:16:19.362Z,1649456179.362 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:19.363Z,1649456179.363 [logger ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.383Z,1649456179.383 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-04-08T22:16:19.383Z,1649456179.383 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.384Z,1649456179.384 [CommandLine](INFO): Join timeout helper Thread ID is 4604
2022-04-08T22:16:19.478Z,1649456179.478 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:19.479Z,1649456179.479 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.483Z,1649456179.483 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-04-08T22:16:19.483Z,1649456179.483 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.484Z,1649456179.484 [CommandExec](INFO): Join timeout helper Thread ID is 4605
2022-04-08T22:16:19.485Z,1649456179.485 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-04-08T22:16:19.485Z,1649456179.485 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.485Z,1649456179.485 [controlThread](INFO): Join timeout helper Thread ID is 4606
2022-04-08T22:16:19.826Z,1649456179.826 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-04-08T22:16:19.827Z,1649456179.827 [controlThread](DEBUG): Uninitializing ControlThread
2022-04-08T22:16:19.827Z,1649456179.827 [AHRS_M2](INFO): Powering down
2022-04-08T22:16:19.899Z,1649456179.899 [NAL9602](INFO): Powering down
2022-04-08T22:16:19.902Z,1649456179.902 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-04-08T22:16:19.903Z,1649456179.903 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-04-08T22:16:19.904Z,1649456179.904 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-04-08T22:16:19.904Z,1649456179.904 [MissionManager](INFO): Uninitializing Mission Default
2022-04-08T22:16:19.904Z,1649456179.904 [Default] Stopped
2022-04-08T22:16:19.904Z,1649456179.904 [Default](DEBUG): Aggregate::uninitialize Default
2022-04-08T22:16:19.904Z,1649456179.904 [Default:B.GoToSurface] Stopped
2022-04-08T22:16:19.905Z,1649456179.905 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-04-08T22:16:19.905Z,1649456179.905 [Default:CheckIn] Stopped
2022-04-08T22:16:19.905Z,1649456179.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-04-08T22:16:19.905Z,1649456179.905 [Default:CheckIn:C.Wait] Stopped
2022-04-08T22:16:19.905Z,1649456179.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-04-08T22:16:19.908Z,1649456179.908 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-04-08T22:16:19.909Z,1649456179.909 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-04-08T22:16:19.909Z,1649456179.909 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-04-08T22:16:19.909Z,1649456179.909 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-04-08T22:16:19.910Z,1649456179.910 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-04-08T22:16:19.910Z,1649456179.910 [BuoyancyServo](INFO): Powering down
2022-04-08T22:16:19.923Z,1649456179.923 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-04-08T22:16:19.923Z,1649456179.923 [ElevatorServo](INFO): Powering down
2022-04-08T22:16:19.924Z,1649456179.924 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-04-08T22:16:19.924Z,1649456179.924 [MassServo](INFO): Powering down
2022-04-08T22:16:19.925Z,1649456179.925 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-04-08T22:16:19.925Z,1649456179.925 [RudderServo](INFO): Powering down
2022-04-08T22:16:19.926Z,1649456179.926 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-04-08T22:16:19.926Z,1649456179.926 [ThrusterServo](INFO): Powering down
2022-04-08T22:16:19.927Z,1649456179.927 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-04-08T22:16:19.927Z,1649456179.927 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-04-08T22:16:19.927Z,1649456179.927 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-04-08T22:16:19.928Z,1649456179.928 [CBIT](DEBUG): Powering off loads.
2022-04-08T22:16:19.938Z,1649456179.938 [CBIT](DEBUG): Disabling WDT.
2022-04-08T22:16:19.950Z,1649456179.950 [CBIT](DEBUG): Opening all GF detection circuits.
2022-04-08T22:16:19.951Z,1649456179.951 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:19.998Z,1649456179.998 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:20.001Z,1649456180.001 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:20.007Z,1649456180.007 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:20.078Z,1649456180.078 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:20.082Z,1649456180.082 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:20.122Z,1649456180.122 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-04-08T22:16:20.187Z,1649456180.187 [logger ThreadHandler](INFO): Thread cancelled.