2022-08-31T13:14:44.556Z,1661951684.556 [Supervisor](DEBUG): Initializing supervisor.
2022-08-31T13:14:44.560Z,1661951684.560 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-08-31T13:14:44.562Z,1661951684.562 [SyncHandler](INFO): Protected caller Thread ID is 8648
2022-08-31T13:14:44.562Z,1661951684.562 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-08-31T13:14:44.563Z,1661951684.563 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-08-31T13:14:44.566Z,1661951684.566 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8649
2022-08-31T13:14:44.570Z,1661951684.570 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-08-31T13:14:44.594Z,1661951684.594 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-08-31T13:14:44.595Z,1661951684.595 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-08-31T13:14:44.595Z,1661951684.595 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8650
2022-08-31T13:14:44.599Z,1661951684.599 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-08-31T13:14:44.600Z,1661951684.600 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-08-31T13:14:44.601Z,1661951684.601 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8651
2022-08-31T13:14:44.603Z,1661951684.603 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-08-31T13:14:44.604Z,1661951684.604 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-08-31T13:14:44.604Z,1661951684.604 [logger ThreadHandler](INFO): Protected caller Thread ID is 8652
2022-08-31T13:14:44.608Z,1661951684.608 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-08-31T13:14:44.608Z,1661951684.608 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-08-31T13:14:44.610Z,1661951684.610 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-08-31T13:14:45.017Z,1661951685.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-08-31T13:14:45.017Z,1661951685.017 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-08-31T13:14:45.096Z,1661951685.096 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-08-31T13:14:45.416Z,1661951685.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-08-31T13:14:45.417Z,1661951685.417 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-08-31T13:14:45.522Z,1661951685.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-08-31T13:14:45.523Z,1661951685.523 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-08-31T13:14:45.713Z,1661951685.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-08-31T13:14:45.713Z,1661951685.713 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-08-31T13:14:46.177Z,1661951686.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-08-31T13:14:46.177Z,1661951686.177 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-08-31T13:14:46.439Z,1661951686.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-08-31T13:14:46.439Z,1661951686.439 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-08-31T13:14:46.648Z,1661951686.648 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-08-31T13:14:46.649Z,1661951686.649 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-08-31T13:14:46.834Z,1661951686.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-08-31T13:14:46.835Z,1661951686.835 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-08-31T13:14:46.931Z,1661951686.931 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-08-31T13:14:46.932Z,1661951686.932 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-08-31T13:14:47.149Z,1661951687.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-08-31T13:14:47.150Z,1661951687.150 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-08-31T13:14:47.511Z,1661951687.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-08-31T13:14:47.512Z,1661951687.512 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-08-31T13:14:47.592Z,1661951687.592 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-08-31T13:14:47.728Z,1661951687.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-08-31T13:14:47.728Z,1661951687.728 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-08-31T13:14:48.413Z,1661951688.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-08-31T13:14:48.414Z,1661951688.414 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-08-31T13:14:48.765Z,1661951688.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-08-31T13:14:48.767Z,1661951688.767 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-polaris/
2022-08-31T13:14:48.768Z,1661951688.768 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/vehicle.cfg
2022-08-31T13:14:48.964Z,1661951688.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Control.cfg
2022-08-31T13:14:49.064Z,1661951689.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Estimation.cfg
2022-08-31T13:14:49.163Z,1661951689.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/BIT.cfg
2022-08-31T13:14:49.262Z,1661951689.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Battery.cfg
2022-08-31T13:14:49.573Z,1661951689.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-08-31T13:14:49.574Z,1661951689.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Simulator.cfg
2022-08-31T13:14:49.849Z,1661951689.849 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Navigation.cfg
2022-08-31T13:14:49.951Z,1661951689.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Derivation.cfg
2022-08-31T13:14:50.072Z,1661951690.072 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/logger.cfg
2022-08-31T13:14:50.182Z,1661951690.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/secure.cfg
2022-08-31T13:14:50.266Z,1661951690.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Dock.cfg
2022-08-31T13:14:50.351Z,1661951690.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Servo.cfg
2022-08-31T13:14:50.459Z,1661951690.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/workSite.cfg
2022-08-31T13:14:50.564Z,1661951690.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Sensor.cfg
2022-08-31T13:14:50.760Z,1661951690.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Science.cfg
2022-08-31T13:14:50.914Z,1661951690.914 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-08-31T13:14:50.924Z,1661951690.924 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-08-31T13:14:51.333Z,1661951691.333 [AHRS_M2] Loaded
2022-08-31T13:14:51.333Z,1661951691.333 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-08-31T13:14:52.101Z,1661951692.101 [BPC1] Loaded
2022-08-31T13:14:52.101Z,1661951692.101 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-08-31T13:14:52.179Z,1661951692.179 [DataOverHttps] Loaded
2022-08-31T13:14:52.180Z,1661951692.180 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-08-31T13:14:52.181Z,1661951692.181 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407E44E0
2022-08-31T13:14:52.181Z,1661951692.181 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8746
2022-08-31T13:14:52.205Z,1661951692.205 [DDM] Loaded
2022-08-31T13:14:52.206Z,1661951692.206 [ComponentRegistry](DEBUG): SyncComponent "DDM" handled in the control thread.
2022-08-31T13:14:52.225Z,1661951692.225 [Depth_Keller] Loaded
2022-08-31T13:14:52.225Z,1661951692.225 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-08-31T13:14:52.230Z,1661951692.230 [DropWeight] Loaded
2022-08-31T13:14:52.230Z,1661951692.230 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-08-31T13:14:52.278Z,1661951692.278 [Micromodem] Loaded
2022-08-31T13:14:52.279Z,1661951692.279 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2022-08-31T13:14:52.349Z,1661951692.349 [NAL9602] Loaded
2022-08-31T13:14:52.349Z,1661951692.349 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-08-31T13:14:52.378Z,1661951692.378 [Onboard] Loaded
2022-08-31T13:14:52.378Z,1661951692.378 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-08-31T13:14:52.379Z,1661951692.379 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408144E0
2022-08-31T13:14:52.379Z,1661951692.379 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8747
2022-08-31T13:14:52.385Z,1661951692.385 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2022-08-31T13:14:52.396Z,1661951692.396 [PowerOnly] Loaded
2022-08-31T13:14:52.396Z,1661951692.396 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-08-31T13:14:52.407Z,1661951692.407 [Power24vConverter] Loaded
2022-08-31T13:14:52.408Z,1661951692.408 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-08-31T13:14:52.421Z,1661951692.421 [Radio_Surface] Loaded
2022-08-31T13:14:52.421Z,1661951692.421 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-08-31T13:14:52.422Z,1661951692.422 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408444E0
2022-08-31T13:14:52.423Z,1661951692.423 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8748
2022-08-31T13:14:52.466Z,1661951692.466 [RDI_Pathfinder] Loaded
2022-08-31T13:14:52.466Z,1661951692.466 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-08-31T13:14:52.493Z,1661951692.493 [RDI_PathfinderUp] Loaded
2022-08-31T13:14:52.493Z,1661951692.493 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2022-08-31T13:14:52.494Z,1661951692.494 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-08-31T13:14:52.495Z,1661951692.495 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-08-31T13:14:52.505Z,1661951692.505 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-08-31T13:14:52.506Z,1661951692.506 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-08-31T13:14:52.614Z,1661951692.614 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-08-31T13:14:52.614Z,1661951692.614 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-08-31T13:14:52.638Z,1661951692.638 [LBLNavigation] Loaded
2022-08-31T13:14:52.638Z,1661951692.638 [ComponentRegistry](DEBUG): SyncComponent "LBLNavigation" handled in the control thread.
2022-08-31T13:14:52.651Z,1661951692.651 [NavChart] Loaded
2022-08-31T13:14:52.651Z,1661951692.651 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-08-31T13:14:52.657Z,1661951692.657 [UniversalFixResidualReporter] Loaded
2022-08-31T13:14:52.657Z,1661951692.657 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-08-31T13:14:52.667Z,1661951692.667 [WorkSite] Loaded
2022-08-31T13:14:52.668Z,1661951692.668 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2022-08-31T13:14:52.668Z,1661951692.668 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-08-31T13:14:52.668Z,1661951692.668 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-08-31T13:14:52.761Z,1661951692.761 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-08-31T13:14:52.761Z,1661951692.761 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-08-31T13:14:52.883Z,1661951692.883 [BuoyancyServo] Loaded
2022-08-31T13:14:52.884Z,1661951692.884 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-08-31T13:14:52.905Z,1661951692.905 [ElevatorServo] Loaded
2022-08-31T13:14:52.905Z,1661951692.905 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-08-31T13:14:52.926Z,1661951692.926 [MassServo] Loaded
2022-08-31T13:14:52.926Z,1661951692.926 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-08-31T13:14:52.946Z,1661951692.946 [RudderServo] Loaded
2022-08-31T13:14:52.946Z,1661951692.946 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-08-31T13:14:52.962Z,1661951692.962 [ThrusterHE] Loaded
2022-08-31T13:14:52.962Z,1661951692.962 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-08-31T13:14:52.962Z,1661951692.962 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-08-31T13:14:52.963Z,1661951692.963 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-08-31T13:14:53.129Z,1661951693.129 [CTD_Seabird] Loaded
2022-08-31T13:14:53.130Z,1661951693.130 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-08-31T13:14:53.131Z,1661951693.131 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409824E0
2022-08-31T13:14:53.131Z,1661951693.131 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8749
2022-08-31T13:14:53.162Z,1661951693.162 [WetLabsSeaOWL_UV_A] Loaded
2022-08-31T13:14:53.162Z,1661951693.162 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2022-08-31T13:14:53.163Z,1661951693.163 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409B24E0
2022-08-31T13:14:53.164Z,1661951693.164 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8750
2022-08-31T13:14:53.164Z,1661951693.164 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-08-31T13:14:53.165Z,1661951693.165 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-08-31T13:14:53.465Z,1661951693.465 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-08-31T13:14:53.465Z,1661951693.465 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-08-31T13:14:53.516Z,1661951693.516 [DepthRateCalculator] Loaded
2022-08-31T13:14:53.517Z,1661951693.517 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-08-31T13:14:53.522Z,1661951693.522 [PitchRateCalculator] Loaded
2022-08-31T13:14:53.522Z,1661951693.522 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-08-31T13:14:53.531Z,1661951693.531 [SpeedCalculator] Loaded
2022-08-31T13:14:53.531Z,1661951693.531 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-08-31T13:14:53.544Z,1661951693.544 [TempGradientCalculator] Loaded
2022-08-31T13:14:53.544Z,1661951693.544 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-08-31T13:14:53.559Z,1661951693.559 [VerticalHomogeneityIndexCalculator] Loaded
2022-08-31T13:14:53.559Z,1661951693.559 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2022-08-31T13:14:53.563Z,1661951693.563 [YawRateCalculator] Loaded
2022-08-31T13:14:53.564Z,1661951693.564 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-08-31T13:14:53.585Z,1661951693.585 [ElevatorOffsetCalculator] Loaded
2022-08-31T13:14:53.586Z,1661951693.586 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-08-31T13:14:53.586Z,1661951693.586 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-08-31T13:14:53.587Z,1661951693.587 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-08-31T13:14:53.726Z,1661951693.726 [SBIT](DEBUG): Construct Startup Built In Test.
2022-08-31T13:14:53.737Z,1661951693.737 [SBIT] Loaded
2022-08-31T13:14:53.738Z,1661951693.738 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-08-31T13:14:53.740Z,1661951693.740 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-08-31T13:14:53.753Z,1661951693.753 [IBIT] Loaded
2022-08-31T13:14:53.753Z,1661951693.753 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-08-31T13:14:53.759Z,1661951693.759 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-08-31T13:14:53.862Z,1661951693.862 [CBIT] Loaded
2022-08-31T13:14:53.862Z,1661951693.862 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-08-31T13:14:53.863Z,1661951693.863 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-08-31T13:14:53.863Z,1661951693.863 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-08-31T13:14:53.919Z,1661951693.919 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-08-31T13:14:53.919Z,1661951693.919 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-08-31T13:14:54.067Z,1661951694.067 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-08-31T13:14:54.067Z,1661951694.067 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-08-31T13:14:54.117Z,1661951694.117 [VerticalControl](DEBUG): Construct VerticalControl.
2022-08-31T13:14:54.166Z,1661951694.166 [VerticalControl] Loaded
2022-08-31T13:14:54.166Z,1661951694.166 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-08-31T13:14:54.169Z,1661951694.169 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-08-31T13:14:54.206Z,1661951694.206 [HorizontalControl] Loaded
2022-08-31T13:14:54.206Z,1661951694.206 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-08-31T13:14:54.208Z,1661951694.208 [SpeedControl](DEBUG): Construct SpeedControl.
2022-08-31T13:14:54.210Z,1661951694.210 [SpeedControl] Loaded
2022-08-31T13:14:54.210Z,1661951694.210 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-08-31T13:14:54.213Z,1661951694.213 [LoopControl](DEBUG): Construct LoopControl.
2022-08-31T13:14:54.213Z,1661951694.213 [LoopControl] Loaded
2022-08-31T13:14:54.214Z,1661951694.214 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-08-31T13:14:54.214Z,1661951694.214 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-08-31T13:14:54.215Z,1661951694.215 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-08-31T13:14:54.241Z,1661951694.241 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-08-31T13:14:54.247Z,1661951694.247 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-08-31T13:14:54.250Z,1661951694.250 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-08-31T13:14:54.261Z,1661951694.261 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-08-31T13:14:54.262Z,1661951694.262 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6E4E0
2022-08-31T13:14:54.262Z,1661951694.262 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8751
2022-08-31T13:14:54.267Z,1661951694.267 [Supervisor](INFO): Main Thread ID is 8272
2022-08-31T13:14:54.267Z,1661951694.267 [Supervisor](DEBUG): Running supervisor.
2022-08-31T13:14:54.267Z,1661951694.267 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8752
2022-08-31T13:14:54.268Z,1661951694.268 [CommandExec](INFO): Initializing the command executive.
2022-08-31T13:14:54.269Z,1661951694.269 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8753
2022-08-31T13:14:54.271Z,1661951694.271 [controlThread ThreadHandler](INFO): Handler Thread ID is 8754
2022-08-31T13:14:54.272Z,1661951694.272 [controlThread](DEBUG): Initializing ControlThread
2022-08-31T13:14:54.279Z,1661951694.279 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-08-31T13:14:54.279Z,1661951694.279 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-08-31T13:14:54.279Z,1661951694.279 [WorkSite](DEBUG): Initializing WorkSite component.
2022-08-31T13:14:54.281Z,1661951694.281 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-08-31T13:14:54.281Z,1661951694.281 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-08-31T13:14:54.281Z,1661951694.281 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-08-31T13:14:54.282Z,1661951694.282 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-08-31T13:14:54.282Z,1661951694.282 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2022-08-31T13:14:54.283Z,1661951694.283 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-08-31T13:14:54.284Z,1661951694.284 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-08-31T13:14:54.284Z,1661951694.284 [SBIT](INFO): Initialize SBIT Component.
2022-08-31T13:14:54.285Z,1661951694.285 [SBIT](IMPORTANT): git: 2022-08-25
2022-08-31T13:14:54.285Z,1661951694.285 [SBIT](INFO): git hash: e1ddd60859d931066d70c4e9618f9367e52a6cfe
2022-08-31T13:14:54.285Z,1661951694.285 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-08-31T13:14:54.286Z,1661951694.286 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020
2022-08-31T13:14:54.287Z,1661951694.287 [SBIT](INFO): Beginning SBIT in 42.000000 seconds.
2022-08-31T13:14:54.288Z,1661951694.288 [IBIT](INFO): Initialize IBIT Component.
2022-08-31T13:14:54.289Z,1661951694.289 [CBIT](DEBUG): Initialize CBIT Component.
2022-08-31T13:14:54.290Z,1661951694.290 [logger ThreadHandler](INFO): Handler Thread ID is 8755
2022-08-31T13:14:54.303Z,1661951694.303 [CBIT](DEBUG): Initialized mux pins.
2022-08-31T13:14:54.304Z,1661951694.304 [CBIT](DEBUG): Initializing the watchdog timer.
2022-08-31T13:14:54.308Z,1661951694.308 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8756
2022-08-31T13:14:54.309Z,1661951694.309 [DataOverHttps](DEBUG): dashIP=128.128.181.226 starts with a digit so assuming it is a numeric IP
2022-08-31T13:14:54.316Z,1661951694.316 [Onboard ThreadHandler](INFO): Handler Thread ID is 8757
2022-08-31T13:14:54.328Z,1661951694.328 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-08-31T13:14:54.329Z,1661951694.329 [CBIT](DEBUG): Initializing heartbeat.
2022-08-31T13:14:54.337Z,1661951694.337 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8758
2022-08-31T13:14:54.360Z,1661951694.360 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8759
2022-08-31T13:14:54.360Z,1661951694.360 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-08-31T13:14:54.372Z,1661951694.372 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8761
2022-08-31T13:14:54.388Z,1661951694.388 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8762
2022-08-31T13:14:54.390Z,1661951694.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-08-31T13:14:54.390Z,1661951694.390 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-08-31T13:14:54.390Z,1661951694.390 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-08-31T13:14:54.391Z,1661951694.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-08-31T13:14:54.391Z,1661951694.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-08-31T13:14:54.391Z,1661951694.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-08-31T13:14:54.391Z,1661951694.391 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-08-31T13:14:54.391Z,1661951694.391 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-08-31T13:14:54.399Z,1661951694.399 [CBIT](DEBUG): Deactivating GF circuits.
2022-08-31T13:14:54.399Z,1661951694.399 [CBIT](DEBUG): Deactivating emergency mode.
2022-08-31T13:14:54.435Z,1661951694.435 [CBIT](DEBUG): Backplane powered.
2022-08-31T13:14:54.436Z,1661951694.436 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-08-31T13:14:54.437Z,1661951694.437 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-08-31T13:14:54.438Z,1661951694.438 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-08-31T13:14:54.439Z,1661951694.439 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-08-31T13:14:54.440Z,1661951694.440 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-08-31T13:14:54.452Z,1661951694.452 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-31T13:14:54.480Z,1661951694.480 [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-08-31T13:14:54.481Z,1661951694.481 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-08-31T13:14:54.562Z,1661951694.562 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-08-31T13:14:54.563Z,1661951694.563 [Default:A.Wait](DEBUG): Construct Wait.
2022-08-31T13:14:54.565Z,1661951694.565 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-08-31T13:14:54.597Z,1661951694.597 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-08-31T13:14:54.624Z,1661951694.624 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-08-31T13:14:54.630Z,1661951694.630 [Default:E.Execute](DEBUG): Construct Execute.
2022-08-31T13:14:54.649Z,1661951694.649 [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-08-31T13:14:54.654Z,1661951694.654 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DDM,Depth_Keller,DropWeight,Micromodem,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,RDI_PathfinderUp,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,LBLNavigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-08-31T13:14:54.681Z,1661951694.681 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-08-31T13:14:54.744Z,1661951694.744 [Radio_Surface](INFO): Powering up
2022-08-31T13:14:54.768Z,1661951694.768 [DDM](INFO): Powering up
2022-08-31T13:14:54.768Z,1661951694.768 [DDM](DEBUG): Initializing DDM.
2022-08-31T13:14:54.796Z,1661951694.796 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2022-08-31T13:14:54.798Z,1661951694.798 [Power24vConverter](INFO): Powering up.
2022-08-31T13:14:54.799Z,1661951694.799 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2022-08-31T13:14:54.847Z,1661951694.847 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-08-31T13:14:54.953Z,1661951694.953 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-08-31T13:14:54.960Z,1661951694.960 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-08-31T13:14:54.961Z,1661951694.961 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-08-31T13:14:54.968Z,1661951694.968 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-08-31T13:14:54.969Z,1661951694.969 [MassServo](DEBUG): Initializing EZServoServo.
2022-08-31T13:14:54.976Z,1661951694.976 [MassServo](DEBUG): Initializing MassServo.
2022-08-31T13:14:54.977Z,1661951694.977 [RudderServo](DEBUG): Initializing EZServoServo.
2022-08-31T13:14:54.984Z,1661951694.984 [RudderServo](DEBUG): Initializing RudderServo.
2022-08-31T13:14:54.984Z,1661951694.984 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-08-31T13:14:54.992Z,1661951694.992 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-08-31T13:14:55.093Z,1661951695.093 [Micromodem](INFO): Powering up
2022-08-31T13:14:55.093Z,1661951695.093 [Micromodem](DEBUG): Initializing Micromodem.
2022-08-31T13:14:59.954Z,1661951699.954 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2022-08-31T13:15:00.553Z,1661951700.553 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-08-31T13:15:01.392Z,1661951701.392 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-08-31T13:15:01.974Z,1661951701.974 [Micromodem](INFO): Nmea in: $CATMG,2022-08-31T13:15:01.307409Z,RTC,RTC*56

2022-08-31T13:15:01.974Z,1661951701.974 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2022-08-31T13:15:01.307409Z,RTC,RTC*56

2022-08-31T13:15:10.417Z,1661951710.417 [DDM](INFO): Latch/Whisker Boards:V1,H_RECOVERY_LATCH
!V2,H_RECOVERY_WHISKERS
2022-08-31T13:15:10.519Z,1661951710.519 [DDM](INFO): Dynamic Docking Module:C REMUS Capture Rev 1.1
2022-08-31T13:15:11.253Z,1661951711.253 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2022-08-31T13:15:11.634Z,1661951711.634 [Micromodem](INFO): Nmea in: $CAERR,131512,NI  ,12,Unknown command*49

2022-08-31T13:15:11.634Z,1661951711.634 [Micromodem](ERROR): Got error from modem: $CAERR,131512,NI  ,12,Unknown command*49

2022-08-31T13:15:22.541Z,1661951722.541 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2022-08-31T13:15:22.542Z,1661951722.542 [NAL9602](INFO): Powering up NAL9602
2022-08-31T13:15:22.948Z,1661951722.948 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2022-08-31T13:15:22.949Z,1661951722.949 [Micromodem](INFO): Nmea out: $CCCFG,POW,20*08

2022-08-31T13:15:23.349Z,1661951723.349 [Micromodem](INFO): Nmea in: $CACFG,POW,20*0A

2022-08-31T13:15:23.350Z,1661951723.350 [Micromodem](INFO): Nmea out: $CCCFG,SRC,0*30

2022-08-31T13:15:23.753Z,1661951723.753 [Micromodem](INFO): Nmea in: $CACFG,SRC,0*32

2022-08-31T13:15:23.754Z,1661951723.754 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2022-08-31T13:15:24.158Z,1661951724.158 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2022-08-31T13:15:24.158Z,1661951724.158 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2022-08-31T13:15:24.566Z,1661951724.566 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2022-08-31T13:15:24.566Z,1661951724.566 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2022-08-31T13:15:25.005Z,1661951725.005 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2022-08-31T13:15:25.006Z,1661951725.006 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2022-08-31T13:15:25.373Z,1661951725.373 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2022-08-31T13:15:25.374Z,1661951725.374 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2022-08-31T13:15:25.773Z,1661951725.773 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2022-08-31T13:15:25.773Z,1661951725.773 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2022-08-31T13:15:26.177Z,1661951726.177 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2022-08-31T13:15:26.178Z,1661951726.178 [Micromodem](INFO): Nmea out: $CCCLK,2022,08,31,13,15,27*4F

2022-08-31T13:15:26.582Z,1661951726.582 [Micromodem](INFO): Nmea in: $CACLK,2022,8,31,13,15,27*7D

2022-08-31T13:15:27.018Z,1661951727.018 [Micromodem](INFO): Nmea in: $CATMS,0,2022-08-31T13:15:28Z*72

2022-08-31T13:15:27.023Z,1661951727.023 [Micromodem](INFO): Nmea in: $CATMG,2022-08-31T13:15:28.025962Z,USER_CMD,RTC*1F

2022-08-31T13:15:33.454Z,1661951733.454 [NAL9602](INFO): NAL9602 initialized
2022-08-31T13:15:35.497Z,1661951735.497 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-08-31T13:15:35.882Z,1661951735.882 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831131534.194624,06,1244,24,0115,0150,208,03,03,01,03,4,-01,-01,2,3,1,0,150,-2.9,13.28,-100,-10.28,-01,-2.72,63,14500,4000*43

2022-08-31T13:15:36.723Z,1661951736.723 [SBIT](IMPORTANT): Beginning Startup BIT
2022-08-31T13:15:36.728Z,1661951736.728 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-31T13:15:47.728Z,1661951747.728 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.037039
CHAN A1 (24V): 0.056629
CHAN A2 (12V): 0.019992
CHAN A3 (5V): 0.000011
CHAN B0 (3.3V): 0.000865
CHAN B1 (3.15aV): 0.000455
CHAN B2 (3.15bV): 0.000858
CHAN B3 (GND): -0.003303
OPEN: 0.005436
Full Scale: +/- 1 mA
2022-08-31T13:15:52.039Z,1661951752.039 [NAL9602](INFO): SBD MO Status=0, MOMSN=44326, MT Status=0, MTMSN=0
2022-08-31T13:15:52.040Z,1661951752.040 [NAL9602](INFO): No messages in MT queue
2022-08-31T13:16:24.768Z,1661951784.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131630.00,A,4015.02704,N,07114.94121,W,0.680,0.00,310822,,,A*73
2022-08-31T13:16:24.771Z,1661951784.771 [NAL9602](INFO): GPS fix at 20220831T131630: (40.250451, -71.249020)
2022-08-31T13:16:24.783Z,1661951784.783 [LBLNavigation](INFO): Reset ping filters
2022-08-31T13:16:24.783Z,1661951784.783 [LBLNavigation](INFO): Reset fix filter
2022-08-31T13:16:30.951Z,1661951790.951 [SBIT](IMPORTANT): SBIT PASSED
2022-08-31T13:16:30.952Z,1661951790.952 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-08-31T13:16:30.954Z,1661951790.954 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-08-31T13:16:30.955Z,1661951790.955 [SBIT](IMPORTANT): DDM.loadAtStartup=1 bool;

2022-08-31T13:16:30.955Z,1661951790.955 [SBIT](IMPORTANT): DockingServo.loadAtStartup=0 bool;

2022-08-31T13:16:30.956Z,1661951790.956 [SBIT](IMPORTANT): LineCapture.armSpeed=1.2 meter_per_second;

2022-08-31T13:16:30.956Z,1661951790.956 [SBIT](IMPORTANT): LineCapture.midcourseSpeed=1.2 meter_per_second;

2022-08-31T13:16:30.957Z,1661951790.957 [SBIT](IMPORTANT): LineCapture.rolloutTimeout=6 minute;

2022-08-31T13:16:30.957Z,1661951790.957 [SBIT](IMPORTANT): LineCapture.shortFinalRange=-1 meter;

2022-08-31T13:16:30.957Z,1661951790.957 [SBIT](IMPORTANT): LineCapture.terminalRange=300 meter;

2022-08-31T13:16:30.958Z,1661951790.958 [SBIT](IMPORTANT): LineCapture.verbose=1 bool;

2022-08-31T13:16:30.958Z,1661951790.958 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=411.225225 cubic_centimeter;

2022-08-31T13:16:30.958Z,1661951790.958 [SBIT](IMPORTANT): VerticalControl.massDefault=-5.740576 millimeter;

2022-08-31T13:16:31.289Z,1661951791.289 [WorkSite](INFO): Already have valid position. Ignoring workSite position from Data/workSite.cfg
2022-08-31T13:16:31.296Z,1661951791.296 [MissionManager](IMPORTANT): Started mission Startup
2022-08-31T13:16:31.297Z,1661951791.297 [Startup] Running Loop=1
2022-08-31T13:16:31.297Z,1661951791.297 [Startup](DEBUG): Aggregate::initialize Startup
2022-08-31T13:16:31.297Z,1661951791.297 [Startup:A.GoToSurface] Running Loop=1
2022-08-31T13:16:31.297Z,1661951791.297 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-31T13:16:31.298Z,1661951791.298 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-31T13:16:31.298Z,1661951791.298 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-31T13:16:31.298Z,1661951791.298 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-31T13:16:31.299Z,1661951791.299 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-31T13:16:31.299Z,1661951791.299 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-31T13:16:31.305Z,1661951791.305 [Startup:StartupSatComms] Running Loop=1
2022-08-31T13:16:31.305Z,1661951791.305 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-08-31T13:16:31.305Z,1661951791.305 [Startup:StartupSatComms:A] Running Loop=1
2022-08-31T13:16:31.649Z,1661951791.649 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-08-31T13:16:33.249Z,1661951793.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131638.00,A,4015.02238,N,07114.94368,W,0.156,0.00,310822,,,A*72
2022-08-31T13:16:33.251Z,1661951793.251 [NAL9602](INFO): GPS fix at 20220831T131638: (40.250373, -71.249061)
2022-08-31T13:16:33.321Z,1661951793.321 [Startup:StartupSatComms:A] Stopped
2022-08-31T13:16:33.321Z,1661951793.321 [Startup:StartupSatComms:B] Running Loop=1
2022-08-31T13:16:33.678Z,1661951793.678 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-31T13:17:07.443Z,1661951827.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=44327, MT Status=2, MTMSN=0
2022-08-31T13:17:07.444Z,1661951827.444 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-31T13:17:25.432Z,1661951845.432 [NAL9602](INFO): SBD MO Status=2, MOMSN=44327, MT Status=2, MTMSN=0
2022-08-31T13:17:25.433Z,1661951845.433 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-31T13:17:33.367Z,1661951853.367 [Startup:StartupSatComms:B](INFO): Timed out from 2022-08-31T13:16:33.3Z
2022-08-31T13:17:33.367Z,1661951853.367 [Startup:StartupSatComms:B] Stopped
2022-08-31T13:17:33.367Z,1661951853.367 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-08-31T13:17:33.367Z,1661951853.367 [Startup:StartupSatComms] Stopped
2022-08-31T13:17:33.371Z,1661951853.371 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-08-31T13:17:33.372Z,1661951853.372 [Startup](INFO): Completed Startup
2022-08-31T13:17:33.372Z,1661951853.372 [MissionManager](INFO): Startup is completed.
2022-08-31T13:17:33.373Z,1661951853.373 [MissionManager](INFO): Uninitializing Mission Startup
2022-08-31T13:17:33.373Z,1661951853.373 [Startup] Stopped
2022-08-31T13:17:33.373Z,1661951853.373 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-08-31T13:17:33.373Z,1661951853.373 [Startup:A.GoToSurface] Stopped
2022-08-31T13:17:33.373Z,1661951853.373 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-31T13:17:33.529Z,1661951853.529 [MissionManager](IMPORTANT): Started mission Default
2022-08-31T13:17:33.529Z,1661951853.529 [Default] Running Loop=1
2022-08-31T13:17:33.529Z,1661951853.529 [Default](DEBUG): Aggregate::initialize Default
2022-08-31T13:17:33.530Z,1661951853.530 [Default:B.GoToSurface] Running Loop=1
2022-08-31T13:17:33.530Z,1661951853.530 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-08-31T13:17:33.530Z,1661951853.530 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-08-31T13:17:33.530Z,1661951853.530 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-08-31T13:17:33.530Z,1661951853.530 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-08-31T13:17:33.531Z,1661951853.531 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-08-31T13:17:33.531Z,1661951853.531 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-08-31T13:17:33.540Z,1661951853.540 [Default:A.Wait] Running Loop=1
2022-08-31T13:17:33.540Z,1661951853.540 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-08-31T13:17:36.260Z,1661951856.260 [NAL9602](INFO): SBD MO Status=1, MOMSN=44327, MT Status=0, MTMSN=0
2022-08-31T13:17:36.315Z,1661951856.315 [NAL9602](INFO): Sent 17 bytes from file Logs/20220831T130230/Courier0007.lzma
2022-08-31T13:17:36.315Z,1661951856.315 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:17:40.133Z,1661951860.133 [CommandExec](IMPORTANT): got command get DropWeight.dropWeightState
2022-08-31T13:17:40.134Z,1661951860.134 [CommandExec](IMPORTANT): DropWeight.dropWeightState 1 bool
2022-08-31T13:17:46.756Z,1661951866.756 [Default:A.Wait](INFO): Done Waiting.
2022-08-31T13:17:46.756Z,1661951866.756 [Default:A.Wait] Stopped
2022-08-31T13:17:46.757Z,1661951866.757 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-08-31T13:17:47.184Z,1661951867.184 [Default:CheckIn] Running Loop=1
2022-08-31T13:17:47.184Z,1661951867.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-31T13:17:47.184Z,1661951867.184 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-31T13:17:47.633Z,1661951867.633 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-08-31T13:17:49.166Z,1661951869.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131754.00,A,4015.02044,N,07114.92277,W,0.972,0.00,310822,,,A*77
2022-08-31T13:17:49.169Z,1661951869.169 [NAL9602](INFO): GPS fix at 20220831T131754: (40.250341, -71.248713)
2022-08-31T13:17:49.223Z,1661951869.223 [Default:CheckIn:Read_GPS] Stopped
2022-08-31T13:17:49.223Z,1661951869.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-31T13:17:49.614Z,1661951869.614 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-08-31T13:18:07.787Z,1661951887.787 [NAL9602](INFO): SBD MO Status=1, MOMSN=44328, MT Status=0, MTMSN=0
2022-08-31T13:18:07.840Z,1661951887.840 [NAL9602](INFO): Sent 52 bytes from file Logs/20220831T130230/Courier0010.lzma
2022-08-31T13:18:07.840Z,1661951887.840 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:18:12.196Z,1661951892.196 [CommandExec](IMPORTANT): got command ibit
2022-08-31T13:18:12.474Z,1661951892.474 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-08-31T13:18:12.474Z,1661951892.474 [IBIT](IMPORTANT): Beginning control surface checks.
2022-08-31T13:18:12.477Z,1661951892.477 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-31T13:18:20.074Z,1661951900.074 [NAL9602](INFO): SBD MO Status=1, MOMSN=44329, MT Status=0, MTMSN=0
2022-08-31T13:18:20.132Z,1661951900.132 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Courier0000.lzma
2022-08-31T13:18:20.132Z,1661951900.132 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:18:25.333Z,1661951905.333 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.033965
CHAN A1 (24V): 0.049466
CHAN A2 (12V): 0.007494
CHAN A3 (5V): 0.001748
CHAN B0 (3.3V): 0.000505
CHAN B1 (3.15aV): 0.000998
CHAN B2 (3.15bV): 0.000796
CHAN B3 (GND): -0.002588
OPEN: 0.006207
Full Scale: +/- 1 mA
2022-08-31T13:18:35.756Z,1661951915.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=44330, MT Status=2, MTMSN=0
2022-08-31T13:18:35.756Z,1661951915.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-31T13:18:37.079Z,1661951917.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,131842.00,A,4015.01866,N,07114.90977,W,0.739,74.96,310822,,,A*40
2022-08-31T13:18:37.086Z,1661951917.086 [NAL9602](INFO): GPS fix at 20220831T131842: (40.250311, -71.248496)
2022-08-31T13:18:53.023Z,1661951933.023 [NAL9602](INFO): SBD MO Status=1, MOMSN=44330, MT Status=0, MTMSN=0
2022-08-31T13:18:53.080Z,1661951933.080 [NAL9602](INFO): Sent 68 bytes from file Logs/20220831T131444/Courier0000.lzma
2022-08-31T13:18:53.080Z,1661951933.080 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:18:56.814Z,1661951936.814 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:18:57.178Z,1661951937.178 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831131856.169073,06,2745,28,0105,0150,216,02,02,01,02,-1,-01,-01,1,3,6,0,150,10.7,-0.11,-100,3.11,-01,-1.08,64,14500,4000*7D

2022-08-31T13:18:59.235Z,1661951939.235 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 40.250313 Longitude: -71.248497
2022-08-31T13:18:59.644Z,1661951939.644 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2022-08-31T13:19:00.018Z,1661951940.018 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-08-31T13:19:00.018Z,1661951940.018 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-08-31T13:19:00.019Z,1661951940.019 [IBIT](IMPORTANT): Pressure:7.513160 PSI
2022-08-31T13:19:00.019Z,1661951940.019 [IBIT](IMPORTANT): Humidity:24.069099 %
2022-08-31T13:19:00.430Z,1661951940.430 [IBIT](IMPORTANT): Vehicle Pitch:1.038866 degrees
2022-08-31T13:19:00.430Z,1661951940.430 [IBIT](IMPORTANT): Vehicle  Roll:2.819756 degrees
2022-08-31T13:19:00.430Z,1661951940.430 [IBIT](IMPORTANT): Vehicle  Heading:350.394379 degrees
2022-08-31T13:19:01.131Z,1661951941.131 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-08-31T13:19:01.132Z,1661951941.132 [IBIT](IMPORTANT): buoyancyNeutral: 411.225220 cc
2022-08-31T13:19:01.132Z,1661951941.132 [IBIT](IMPORTANT): massDefault: -0.574058 cm
2022-08-31T13:19:01.132Z,1661951941.132 [IBIT](IMPORTANT): stopDepth: 35.000000 m
2022-08-31T13:19:01.133Z,1661951941.133 [IBIT](IMPORTANT): abortDepth: 50.000000 m
2022-08-31T13:19:01.133Z,1661951941.133 [IBIT](IMPORTANT): IBIT PASSED
2022-08-31T13:19:02.896Z,1661951942.896 [NAL9602](INFO): SBD MO Status=1, MOMSN=44331, MT Status=0, MTMSN=0
2022-08-31T13:19:02.946Z,1661951942.946 [NAL9602](INFO): Sent 72 bytes from file Logs/20220831T131444/Courier0004.lzma
2022-08-31T13:19:02.947Z,1661951942.947 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:19:12.435Z,1661951952.435 [NAL9602](INFO): SBD MO Status=1, MOMSN=44332, MT Status=0, MTMSN=0
2022-08-31T13:19:12.492Z,1661951952.492 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T130230/Express0008.lzma
2022-08-31T13:19:12.492Z,1661951952.492 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:19:21.441Z,1661951961.441 [NAL9602](INFO): SBD MO Status=1, MOMSN=44333, MT Status=0, MTMSN=0
2022-08-31T13:19:21.504Z,1661951961.504 [NAL9602](INFO): Sent 33 bytes from file Logs/20220831T130230/Express0008.lzma
2022-08-31T13:19:21.505Z,1661951961.505 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:19:30.416Z,1661951970.416 [NAL9602](INFO): SBD MO Status=1, MOMSN=44334, MT Status=0, MTMSN=0
2022-08-31T13:19:30.460Z,1661951970.460 [NAL9602](INFO): Sent 172 bytes from file Logs/20220831T130230/Express0011.lzma
2022-08-31T13:19:30.460Z,1661951970.460 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:19:38.540Z,1661951978.540 [NAL9602](INFO): SBD MO Status=1, MOMSN=44335, MT Status=0, MTMSN=0
2022-08-31T13:19:38.592Z,1661951978.592 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0001.lzma
2022-08-31T13:19:38.592Z,1661951978.592 [NAL9602](INFO): Packets left to send: 4
2022-08-31T13:19:50.576Z,1661951990.576 [NAL9602](INFO): SBD MO Status=1, MOMSN=44336, MT Status=0, MTMSN=0
2022-08-31T13:19:50.628Z,1661951990.628 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0001.lzma
2022-08-31T13:19:50.628Z,1661951990.628 [NAL9602](INFO): Packets left to send: 3
2022-08-31T13:19:56.386Z,1661951996.386 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2022-08-31T13:20:02.106Z,1661952002.106 [CommandLine](INFO): End of History
2022-08-31T13:20:02.807Z,1661952002.807 [NAL9602](INFO): SBD MO Status=1, MOMSN=44337, MT Status=0, MTMSN=0
2022-08-31T13:20:02.859Z,1661952002.859 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0001.lzma
2022-08-31T13:20:02.859Z,1661952002.859 [NAL9602](INFO): Packets left to send: 2
2022-08-31T13:20:03.349Z,1661952003.349 [CommandLine](INFO): End of History
2022-08-31T13:20:05.171Z,1661952005.171 [CommandLine](INFO): End of History
2022-08-31T13:20:06.664Z,1661952006.664 [CommandLine](INFO): End of History
2022-08-31T13:20:07.539Z,1661952007.539 [CommandLine](INFO): End of History
2022-08-31T13:20:08.590Z,1661952008.590 [CommandLine](INFO): End of History
2022-08-31T13:20:10.023Z,1661952010.023 [CommandLine](INFO): End of History
2022-08-31T13:20:11.076Z,1661952011.076 [CommandLine](INFO): End of History
2022-08-31T13:20:12.129Z,1661952012.129 [CommandLine](INFO): End of History
2022-08-31T13:20:13.259Z,1661952013.259 [CommandLine](INFO): End of History
2022-08-31T13:20:14.267Z,1661952014.267 [CommandLine](INFO): End of History
2022-08-31T13:20:15.441Z,1661952015.441 [CommandLine](INFO): End of History
2022-08-31T13:20:17.139Z,1661952017.139 [NAL9602](INFO): SBD MO Status=1, MOMSN=44338, MT Status=0, MTMSN=0
2022-08-31T13:20:17.200Z,1661952017.200 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0001.lzma
2022-08-31T13:20:17.200Z,1661952017.200 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:20:18.552Z,1661952018.552 [CommandLine](INFO): End of History
2022-08-31T13:20:20.260Z,1661952020.260 [CommandLine](INFO): End of History
2022-08-31T13:20:21.853Z,1661952021.853 [CommandLine](INFO): End of History
2022-08-31T13:20:22.862Z,1661952022.862 [CommandLine](INFO): End of History
2022-08-31T13:20:23.715Z,1661952023.715 [CommandLine](INFO): End of History
2022-08-31T13:20:24.922Z,1661952024.922 [CommandLine](INFO): End of History
2022-08-31T13:20:26.144Z,1661952026.144 [CommandLine](INFO): End of History
2022-08-31T13:20:27.169Z,1661952027.169 [CommandLine](INFO): End of History
2022-08-31T13:20:28.253Z,1661952028.253 [CommandLine](INFO): End of History
2022-08-31T13:20:28.407Z,1661952028.407 [NAL9602](INFO): SBD MO Status=1, MOMSN=44339, MT Status=0, MTMSN=0
2022-08-31T13:20:28.456Z,1661952028.456 [NAL9602](INFO): Sent 160 bytes from file Logs/20220831T131444/Express0001.lzma
2022-08-31T13:20:28.456Z,1661952028.456 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:20:29.684Z,1661952029.684 [CommandLine](INFO): End of History
2022-08-31T13:20:31.312Z,1661952031.312 [CommandLine](INFO): End of History
2022-08-31T13:20:32.462Z,1661952032.462 [CommandLine](INFO): End of History
2022-08-31T13:20:33.348Z,1661952033.348 [CommandLine](INFO): End of History
2022-08-31T13:20:34.370Z,1661952034.370 [CommandLine](INFO): End of History
2022-08-31T13:20:35.529Z,1661952035.529 [CommandLine](INFO): End of History
2022-08-31T13:20:36.532Z,1661952036.532 [CommandLine](INFO): End of History
2022-08-31T13:20:37.722Z,1661952037.722 [CommandLine](INFO): End of History
2022-08-31T13:20:38.802Z,1661952038.802 [CommandLine](INFO): End of History
2022-08-31T13:20:39.953Z,1661952039.953 [CommandLine](INFO): End of History
2022-08-31T13:20:40.636Z,1661952040.636 [NAL9602](INFO): SBD MO Status=1, MOMSN=44340, MT Status=0, MTMSN=0
2022-08-31T13:20:40.693Z,1661952040.693 [NAL9602](INFO): Sent 173 bytes from file Logs/20220831T131444/Express0005.lzma
2022-08-31T13:20:40.694Z,1661952040.694 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:20:41.050Z,1661952041.050 [CommandLine](INFO): End of History
2022-08-31T13:20:42.676Z,1661952042.676 [CommandLine](INFO): End of History
2022-08-31T13:20:44.262Z,1661952044.262 [CommandLine](INFO): End of History
2022-08-31T13:20:45.453Z,1661952045.453 [CommandLine](INFO): End of History
2022-08-31T13:20:46.563Z,1661952046.563 [CommandLine](INFO): End of History
2022-08-31T13:20:47.615Z,1661952047.615 [NAL9602](INFO): SBD MO Status=0, MOMSN=44341, MT Status=0, MTMSN=0
2022-08-31T13:20:47.708Z,1661952047.708 [Default:CheckIn:Read_Iridium] Stopped
2022-08-31T13:20:47.708Z,1661952047.708 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-31T13:20:47.708Z,1661952047.708 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-31T13:20:47.833Z,1661952047.833 [CommandLine](INFO): End of History
2022-08-31T13:20:48.790Z,1661952048.790 [CommandLine](INFO): End of History
2022-08-31T13:20:49.952Z,1661952049.952 [CommandLine](INFO): End of History
2022-08-31T13:20:50.041Z,1661952050.041 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2022-08-31T13:20:51.183Z,1661952051.183 [CommandLine](INFO): End of History
2022-08-31T13:20:52.385Z,1661952052.385 [CommandLine](INFO): End of History
2022-08-31T13:20:53.541Z,1661952053.541 [CommandLine](INFO): End of History
2022-08-31T13:20:54.574Z,1661952054.574 [CommandLine](INFO): End of History
2022-08-31T13:20:55.667Z,1661952055.667 [CommandLine](INFO): End of History
2022-08-31T13:20:56.716Z,1661952056.716 [CommandLine](INFO): End of History
2022-08-31T13:20:57.839Z,1661952057.839 [CommandLine](INFO): End of History
2022-08-31T13:20:58.795Z,1661952058.795 [CommandLine](INFO): End of History
2022-08-31T13:20:59.964Z,1661952059.964 [CommandLine](INFO): End of History
2022-08-31T13:21:02.215Z,1661952062.215 [CommandLine](INFO): End of History
2022-08-31T13:21:04.448Z,1661952064.448 [CommandLine](INFO): End of History
2022-08-31T13:21:05.641Z,1661952065.641 [CommandLine](INFO): End of History
2022-08-31T13:21:06.734Z,1661952066.734 [CommandLine](INFO): End of History
2022-08-31T13:21:07.838Z,1661952067.838 [CommandLine](INFO): End of History
2022-08-31T13:21:08.864Z,1661952068.864 [CommandLine](INFO): End of History
2022-08-31T13:21:10.023Z,1661952070.023 [CommandLine](INFO): End of History
2022-08-31T13:21:11.135Z,1661952071.135 [CommandLine](INFO): End of History
2022-08-31T13:21:12.155Z,1661952072.155 [CommandLine](INFO): End of History
2022-08-31T13:21:13.275Z,1661952073.275 [CommandLine](INFO): End of History
2022-08-31T13:21:14.398Z,1661952074.398 [CommandLine](INFO): End of History
2022-08-31T13:21:15.511Z,1661952075.511 [CommandLine](INFO): End of History
2022-08-31T13:21:16.517Z,1661952076.517 [CommandLine](INFO): End of History
2022-08-31T13:21:17.750Z,1661952077.750 [CommandLine](INFO): End of History
2022-08-31T13:21:18.325Z,1661952078.325 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-31T13:21:18.838Z,1661952078.838 [CommandLine](INFO): End of History
2022-08-31T13:21:19.980Z,1661952079.980 [CommandLine](INFO): End of History
2022-08-31T13:21:21.013Z,1661952081.013 [CommandLine](INFO): End of History
2022-08-31T13:21:22.089Z,1661952082.089 [CommandLine](INFO): End of History
2022-08-31T13:21:23.115Z,1661952083.115 [CommandLine](INFO): End of History
2022-08-31T13:21:24.186Z,1661952084.186 [CommandLine](INFO): End of History
2022-08-31T13:21:25.341Z,1661952085.341 [CommandLine](INFO): End of History
2022-08-31T13:21:26.345Z,1661952086.345 [CommandLine](INFO): End of History
2022-08-31T13:21:27.500Z,1661952087.500 [CommandLine](INFO): End of History
2022-08-31T13:21:28.647Z,1661952088.647 [CommandLine](INFO): End of History
2022-08-31T13:21:29.801Z,1661952089.801 [CommandLine](INFO): End of History
2022-08-31T13:21:30.875Z,1661952090.875 [CommandLine](INFO): End of History
2022-08-31T13:21:32.100Z,1661952092.100 [CommandLine](INFO): End of History
2022-08-31T13:21:33.151Z,1661952093.151 [CommandLine](INFO): End of History
2022-08-31T13:21:34.240Z,1661952094.240 [CommandLine](INFO): End of History
2022-08-31T13:21:35.438Z,1661952095.438 [CommandLine](INFO): End of History
2022-08-31T13:21:36.421Z,1661952096.421 [CommandLine](INFO): End of History
2022-08-31T13:21:37.552Z,1661952097.552 [CommandLine](INFO): End of History
2022-08-31T13:21:38.590Z,1661952098.590 [CommandLine](INFO): End of History
2022-08-31T13:21:39.677Z,1661952099.677 [CommandLine](INFO): End of History
2022-08-31T13:21:40.745Z,1661952100.745 [CommandLine](INFO): End of History
2022-08-31T13:21:41.959Z,1661952101.959 [CommandLine](INFO): End of History
2022-08-31T13:21:42.919Z,1661952102.919 [CommandLine](INFO): End of History
2022-08-31T13:21:44.084Z,1661952104.084 [CommandLine](INFO): End of History
2022-08-31T13:21:45.380Z,1661952105.380 [CommandLine](INFO): End of History
2022-08-31T13:21:46.485Z,1661952106.485 [CommandLine](INFO): End of History
2022-08-31T13:21:47.588Z,1661952107.588 [CommandLine](INFO): End of History
2022-08-31T13:21:48.544Z,1661952108.544 [CommandLine](INFO): End of History
2022-08-31T13:21:49.696Z,1661952109.696 [CommandLine](INFO): End of History
2022-08-31T13:21:50.735Z,1661952110.735 [CommandLine](INFO): End of History
2022-08-31T13:21:51.765Z,1661952111.765 [CommandLine](INFO): End of History
2022-08-31T13:21:52.809Z,1661952112.809 [CommandLine](INFO): End of History
2022-08-31T13:21:53.910Z,1661952113.910 [CommandLine](INFO): End of History
2022-08-31T13:21:55.123Z,1661952115.123 [CommandLine](INFO): End of History
2022-08-31T13:21:56.179Z,1661952116.179 [CommandLine](INFO): End of History
2022-08-31T13:21:57.313Z,1661952117.313 [CommandLine](INFO): End of History
2022-08-31T13:21:58.423Z,1661952118.423 [CommandLine](INFO): End of History
2022-08-31T13:21:59.467Z,1661952119.467 [CommandLine](INFO): End of History
2022-08-31T13:22:00.517Z,1661952120.517 [CommandLine](INFO): End of History
2022-08-31T13:22:01.655Z,1661952121.655 [CommandLine](INFO): End of History
2022-08-31T13:22:02.670Z,1661952122.670 [CommandLine](INFO): End of History
2022-08-31T13:22:03.812Z,1661952123.812 [CommandLine](INFO): End of History
2022-08-31T13:22:05.049Z,1661952125.049 [CommandLine](INFO): End of History
2022-08-31T13:22:06.180Z,1661952126.180 [CommandLine](INFO): End of History
2022-08-31T13:22:07.227Z,1661952127.227 [CommandLine](INFO): End of History
2022-08-31T13:22:08.356Z,1661952128.356 [CommandLine](INFO): End of History
2022-08-31T13:22:09.458Z,1661952129.458 [CommandLine](INFO): End of History
2022-08-31T13:22:10.526Z,1661952130.526 [CommandLine](INFO): End of History
2022-08-31T13:22:11.668Z,1661952131.668 [CommandLine](INFO): End of History
2022-08-31T13:22:12.747Z,1661952132.747 [CommandLine](INFO): End of History
2022-08-31T13:22:13.878Z,1661952133.878 [CommandLine](INFO): End of History
2022-08-31T13:22:14.973Z,1661952134.973 [CommandLine](INFO): End of History
2022-08-31T13:22:16.025Z,1661952136.025 [CommandLine](INFO): End of History
2022-08-31T13:22:17.114Z,1661952137.114 [CommandLine](INFO): End of History
2022-08-31T13:22:18.195Z,1661952138.195 [CommandLine](INFO): End of History
2022-08-31T13:22:19.228Z,1661952139.228 [CommandLine](INFO): End of History
2022-08-31T13:22:20.376Z,1661952140.376 [CommandLine](INFO): End of History
2022-08-31T13:22:21.586Z,1661952141.586 [CommandLine](INFO): End of History
2022-08-31T13:22:22.644Z,1661952142.644 [CommandLine](INFO): End of History
2022-08-31T13:22:23.699Z,1661952143.699 [CommandLine](INFO): End of History
2022-08-31T13:22:24.776Z,1661952144.776 [CommandLine](INFO): End of History
2022-08-31T13:22:26.014Z,1661952146.014 [CommandLine](INFO): End of History
2022-08-31T13:22:27.129Z,1661952147.129 [CommandLine](INFO): End of History
2022-08-31T13:22:28.108Z,1661952148.108 [CommandLine](INFO): End of History
2022-08-31T13:22:29.264Z,1661952149.264 [CommandLine](INFO): End of History
2022-08-31T13:22:30.338Z,1661952150.338 [CommandLine](INFO): End of History
2022-08-31T13:22:31.379Z,1661952151.379 [CommandLine](INFO): End of History
2022-08-31T13:22:32.445Z,1661952152.445 [CommandLine](INFO): End of History
2022-08-31T13:22:33.569Z,1661952153.569 [CommandLine](INFO): End of History
2022-08-31T13:22:34.545Z,1661952154.545 [CommandLine](INFO): End of History
2022-08-31T13:22:35.795Z,1661952155.795 [CommandLine](INFO): End of History
2022-08-31T13:22:36.839Z,1661952156.839 [CommandLine](INFO): End of History
2022-08-31T13:22:37.877Z,1661952157.877 [CommandLine](INFO): End of History
2022-08-31T13:22:39.040Z,1661952159.040 [CommandLine](INFO): End of History
2022-08-31T13:22:40.203Z,1661952160.203 [CommandLine](INFO): End of History
2022-08-31T13:22:41.347Z,1661952161.347 [CommandLine](INFO): End of History
2022-08-31T13:22:42.307Z,1661952162.307 [CommandLine](INFO): End of History
2022-08-31T13:22:43.434Z,1661952163.434 [CommandLine](INFO): End of History
2022-08-31T13:22:44.468Z,1661952164.468 [CommandLine](INFO): End of History
2022-08-31T13:22:45.507Z,1661952165.507 [CommandLine](INFO): End of History
2022-08-31T13:22:46.554Z,1661952166.554 [CommandLine](INFO): End of History
2022-08-31T13:22:47.723Z,1661952167.723 [CommandLine](INFO): End of History
2022-08-31T13:22:48.779Z,1661952168.779 [CommandLine](INFO): End of History
2022-08-31T13:22:49.936Z,1661952169.936 [CommandLine](INFO): End of History
2022-08-31T13:22:51.020Z,1661952171.020 [CommandLine](INFO): End of History
2022-08-31T13:22:52.077Z,1661952172.077 [CommandLine](INFO): End of History
2022-08-31T13:22:53.213Z,1661952173.213 [CommandLine](INFO): End of History
2022-08-31T13:22:54.302Z,1661952174.302 [CommandLine](INFO): End of History
2022-08-31T13:22:55.464Z,1661952175.464 [CommandLine](INFO): End of History
2022-08-31T13:22:56.519Z,1661952176.519 [CommandLine](INFO): End of History
2022-08-31T13:22:57.621Z,1661952177.621 [CommandLine](INFO): End of History
2022-08-31T13:22:58.665Z,1661952178.665 [CommandLine](INFO): End of History
2022-08-31T13:22:59.794Z,1661952179.794 [CommandLine](INFO): End of History
2022-08-31T13:23:00.806Z,1661952180.806 [CommandLine](INFO): End of History
2022-08-31T13:23:01.740Z,1661952181.740 [BPC1](ERROR): Battery stick #62 (s/n: 0138) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2022-08-31T13:23:01.741Z,1661952181.741 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2022-08-31T13:23:01.748Z,1661952181.748 [BPC1](INFO): Received data from all battery sticks.
2022-08-31T13:23:02.079Z,1661952182.079 [CommandLine](INFO): End of History
2022-08-31T13:23:03.168Z,1661952183.168 [CommandLine](INFO): End of History
2022-08-31T13:23:04.241Z,1661952184.241 [CommandLine](INFO): End of History
2022-08-31T13:23:05.424Z,1661952185.424 [CommandLine](INFO): End of History
2022-08-31T13:23:06.435Z,1661952186.435 [CommandLine](INFO): End of History
2022-08-31T13:23:07.588Z,1661952187.588 [CommandLine](INFO): End of History
2022-08-31T13:23:08.570Z,1661952188.570 [CommandLine](INFO): End of History
2022-08-31T13:23:09.747Z,1661952189.747 [CommandLine](INFO): End of History
2022-08-31T13:23:10.827Z,1661952190.827 [CommandLine](INFO): End of History
2022-08-31T13:23:11.981Z,1661952191.981 [CommandLine](INFO): End of History
2022-08-31T13:23:13.001Z,1661952193.001 [CommandLine](INFO): End of History
2022-08-31T13:23:14.123Z,1661952194.123 [CommandLine](INFO): End of History
2022-08-31T13:23:15.301Z,1661952195.301 [CommandLine](INFO): End of History
2022-08-31T13:23:16.360Z,1661952196.360 [CommandLine](INFO): End of History
2022-08-31T13:23:17.432Z,1661952197.432 [CommandLine](INFO): End of History
2022-08-31T13:23:18.487Z,1661952198.487 [CommandLine](INFO): End of History
2022-08-31T13:23:19.622Z,1661952199.622 [CommandLine](INFO): End of History
2022-08-31T13:23:20.642Z,1661952200.642 [CommandLine](INFO): End of History
2022-08-31T13:23:21.755Z,1661952201.755 [CommandLine](INFO): End of History
2022-08-31T13:23:22.820Z,1661952202.820 [CommandLine](INFO): End of History
2022-08-31T13:23:23.882Z,1661952203.882 [CommandLine](INFO): End of History
2022-08-31T13:23:24.965Z,1661952204.965 [CommandLine](INFO): End of History
2022-08-31T13:23:26.108Z,1661952206.108 [CommandLine](INFO): End of History
2022-08-31T13:23:28.699Z,1661952208.699 [CommandLine](INFO): End of History
2022-08-31T13:23:29.844Z,1661952209.844 [CommandLine](INFO): End of History
2022-08-31T13:23:30.780Z,1661952210.780 [CommandLine](INFO): End of History
2022-08-31T13:23:31.891Z,1661952211.891 [CommandLine](INFO): End of History
2022-08-31T13:23:32.938Z,1661952212.938 [CommandLine](INFO): End of History
2022-08-31T13:23:33.981Z,1661952213.981 [CommandLine](INFO): End of History
2022-08-31T13:23:35.111Z,1661952215.111 [CommandLine](INFO): End of History
2022-08-31T13:23:36.206Z,1661952216.206 [CommandLine](INFO): End of History
2022-08-31T13:23:37.200Z,1661952217.200 [CommandLine](INFO): End of History
2022-08-31T13:23:38.232Z,1661952218.232 [CommandLine](INFO): End of History
2022-08-31T13:23:39.238Z,1661952219.238 [CommandLine](INFO): End of History
2022-08-31T13:23:40.324Z,1661952220.324 [CommandLine](INFO): End of History
2022-08-31T13:23:41.493Z,1661952221.493 [CommandLine](INFO): End of History
2022-08-31T13:23:42.466Z,1661952222.466 [CommandLine](INFO): End of History
2022-08-31T13:23:43.592Z,1661952223.592 [CommandLine](INFO): End of History
2022-08-31T13:23:44.599Z,1661952224.599 [CommandLine](INFO): End of History
2022-08-31T13:23:45.702Z,1661952225.702 [CommandLine](INFO): End of History
2022-08-31T13:23:46.888Z,1661952226.888 [CommandLine](INFO): End of History
2022-08-31T13:23:49.364Z,1661952229.364 [CommandLine](INFO): End of History
2022-08-31T13:23:50.499Z,1661952230.499 [CommandLine](INFO): End of History
2022-08-31T13:23:51.635Z,1661952231.635 [CommandLine](INFO): End of History
2022-08-31T13:23:52.678Z,1661952232.678 [CommandLine](INFO): End of History
2022-08-31T13:23:53.936Z,1661952233.936 [CommandLine](INFO): End of History
2022-08-31T13:23:54.933Z,1661952234.933 [CommandLine](INFO): End of History
2022-08-31T13:23:56.072Z,1661952236.072 [CommandLine](INFO): End of History
2022-08-31T13:23:57.203Z,1661952237.203 [CommandLine](INFO): End of History
2022-08-31T13:23:58.190Z,1661952238.190 [CommandLine](INFO): End of History
2022-08-31T13:23:59.366Z,1661952239.366 [CommandLine](INFO): End of History
2022-08-31T13:24:00.442Z,1661952240.442 [CommandLine](INFO): End of History
2022-08-31T13:24:24.163Z,1661952264.163 [CommandExec](IMPORTANT): got command get DropWeight.dropWeightState
2022-08-31T13:24:24.168Z,1661952264.168 [CommandExec](IMPORTANT): DropWeight.dropWeightState 1 bool
2022-08-31T13:24:31.988Z,1661952271.988 [CommandExec](IMPORTANT): got command ibit
2022-08-31T13:24:32.261Z,1661952272.261 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-08-31T13:24:32.261Z,1661952272.261 [IBIT](IMPORTANT): Beginning control surface checks.
2022-08-31T13:24:32.264Z,1661952272.264 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-31T13:24:33.856Z,1661952273.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132440.00,A,4014.98565,N,07114.80127,W,0.564,111.50,310822,,,A*7C
2022-08-31T13:24:33.858Z,1661952273.858 [NAL9602](INFO): GPS fix at 20220831T132440: (40.249761, -71.246688)
2022-08-31T13:24:43.214Z,1661952283.214 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.032107
CHAN A1 (24V): 0.037466
CHAN A2 (12V): 0.009024
CHAN A3 (5V): 0.001619
CHAN B0 (3.3V): 0.000563
CHAN B1 (3.15aV): 0.000434
CHAN B2 (3.15bV): 0.001011
CHAN B3 (GND): -0.003096
OPEN: 0.006104
Full Scale: +/- 1 mA
2022-08-31T13:24:58.090Z,1661952298.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=44342, MT Status=2, MTMSN=0
2022-08-31T13:24:58.090Z,1661952298.090 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-31T13:25:18.744Z,1661952318.744 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 40.249760 Longitude: -71.246689
2022-08-31T13:25:19.136Z,1661952319.136 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2022-08-31T13:25:19.136Z,1661952319.136 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-08-31T13:25:19.136Z,1661952319.136 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-08-31T13:25:19.522Z,1661952319.522 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-08-31T13:25:19.522Z,1661952319.522 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-08-31T13:25:19.523Z,1661952319.523 [IBIT](IMPORTANT): Pressure:7.555383 PSI
2022-08-31T13:25:19.523Z,1661952319.523 [IBIT](IMPORTANT): Humidity:24.093517 %
2022-08-31T13:25:19.938Z,1661952319.938 [IBIT](IMPORTANT): Vehicle Pitch:1.568420 degrees
2022-08-31T13:25:19.938Z,1661952319.938 [IBIT](IMPORTANT): Vehicle  Roll:1.539035 degrees
2022-08-31T13:25:19.938Z,1661952319.938 [IBIT](IMPORTANT): Vehicle  Heading:2.135215 degrees
2022-08-31T13:25:20.344Z,1661952320.344 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-08-31T13:25:20.344Z,1661952320.344 [IBIT](IMPORTANT): buoyancyNeutral: 411.225220 cc
2022-08-31T13:25:20.345Z,1661952320.345 [IBIT](IMPORTANT): massDefault: -0.574058 cm
2022-08-31T13:25:20.345Z,1661952320.345 [IBIT](IMPORTANT): stopDepth: 35.000000 m
2022-08-31T13:25:20.345Z,1661952320.345 [IBIT](IMPORTANT): abortDepth: 50.000000 m
2022-08-31T13:25:20.345Z,1661952320.345 [IBIT](IMPORTANT): IBIT PASSED
2022-08-31T13:25:23.952Z,1661952323.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=44342, MT Status=0, MTMSN=0
2022-08-31T13:25:23.952Z,1661952323.952 [NAL9602](INFO): No messages in MT queue
2022-08-31T13:25:48.227Z,1661952348.227 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-31T13:25:48.227Z,1661952348.227 [Default:CheckIn:C.Wait] Stopped
2022-08-31T13:25:48.227Z,1661952348.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-31T13:25:48.228Z,1661952348.228 [Default:CheckIn:D] Running Loop=1
2022-08-31T13:25:48.608Z,1661952348.608 [Default:CheckIn:D] Stopped
2022-08-31T13:25:48.609Z,1661952348.609 [Default:CheckIn:E] Running Loop=1
2022-08-31T13:25:49.068Z,1661952349.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.251318 min 
2022-08-31T13:25:49.069Z,1661952349.069 [Default:CheckIn:E] Stopped
2022-08-31T13:25:49.069Z,1661952349.069 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-31T13:25:49.069Z,1661952349.069 [Default:CheckIn] Stopped
2022-08-31T13:25:49.069Z,1661952349.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-31T13:25:49.070Z,1661952349.070 [Default:CheckIn](INFO): Running loop #2
2022-08-31T13:25:49.070Z,1661952349.070 [Default:CheckIn] Running Loop=2
2022-08-31T13:25:49.070Z,1661952349.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-31T13:25:49.070Z,1661952349.070 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-31T13:25:51.022Z,1661952351.022 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132557.00,A,4014.97979,N,07114.77966,W,0.816,106.80,310822,,,A*73
2022-08-31T13:25:51.024Z,1661952351.024 [NAL9602](INFO): GPS fix at 20220831T132557: (40.249663, -71.246328)
2022-08-31T13:25:51.122Z,1661952351.122 [Default:CheckIn:Read_GPS] Stopped
2022-08-31T13:25:51.122Z,1661952351.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-31T13:26:08.134Z,1661952368.134 [NAL9602](INFO): SBD MO Status=1, MOMSN=44343, MT Status=0, MTMSN=0
2022-08-31T13:26:08.183Z,1661952368.183 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Courier0007.lzma
2022-08-31T13:26:08.183Z,1661952368.183 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:26:16.008Z,1661952376.008 [NAL9602](INFO): SBD MO Status=1, MOMSN=44344, MT Status=0, MTMSN=0
2022-08-31T13:26:16.060Z,1661952376.060 [NAL9602](INFO): Sent 18 bytes from file Logs/20220831T131444/Courier0007.lzma
2022-08-31T13:26:16.060Z,1661952376.060 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:26:23.296Z,1661952383.296 [NAL9602](INFO): SBD MO Status=1, MOMSN=44345, MT Status=0, MTMSN=0
2022-08-31T13:26:23.344Z,1661952383.344 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0008.lzma
2022-08-31T13:26:23.344Z,1661952383.344 [NAL9602](INFO): Packets left to send: 5
2022-08-31T13:26:25.314Z,1661952385.314 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-08-31T13:26:25.704Z,1661952385.704 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831132624.187779,06,247,23,0128,0150,149,01,01,00,01,5,-01,-01,2,3,1,0,150,-27.1,6.88,-100,-3.88,-01,-0.47,54,14500,4000*4F

2022-08-31T13:26:32.682Z,1661952392.682 [NAL9602](INFO): SBD MO Status=1, MOMSN=44346, MT Status=0, MTMSN=0
2022-08-31T13:26:32.736Z,1661952392.736 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0008.lzma
2022-08-31T13:26:32.736Z,1661952392.736 [NAL9602](INFO): Packets left to send: 4
2022-08-31T13:26:50.145Z,1661952410.145 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=44347, MT Status=1, MTMSN=1681
2022-08-31T13:26:50.196Z,1661952410.196 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0008.lzma
2022-08-31T13:26:50.196Z,1661952410.196 [NAL9602](INFO): Packets left to send: 3
2022-08-31T13:26:50.648Z,1661952410.648 [NAL9602](INFO): Received command: DDM.loadAtStartup=0 bool
2022-08-31T13:26:50.688Z,1661952410.688 [CommandExec](IMPORTANT): got command DDM 
2022-08-31T13:26:50.688Z,1661952410.688 [CommandExec](FAULT): Incomplete syntax. Try: help DDM
2022-08-31T13:27:04.728Z,1661952424.728 [NAL9602](INFO): SBD MO Status=1, MOMSN=44348, MT Status=0, MTMSN=0
2022-08-31T13:27:04.780Z,1661952424.780 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0008.lzma
2022-08-31T13:27:04.780Z,1661952424.780 [NAL9602](INFO): Packets left to send: 2
2022-08-31T13:27:07.260Z,1661952427.260 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-08-31T13:27:07.641Z,1661952427.641 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831132706.170798,06,2381,27,0090,0150,216,02,02,01,02,6,-01,-01,2,3,1,0,150,-20.7,3.88,12,-0.88,-01,-2.62,64,14500,4000*60

2022-08-31T13:27:17.473Z,1661952437.473 [NAL9602](INFO): SBD MO Status=1, MOMSN=44349, MT Status=0, MTMSN=0
2022-08-31T13:27:17.537Z,1661952437.537 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0008.lzma
2022-08-31T13:27:17.538Z,1661952437.538 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:27:31.199Z,1661952451.199 [NAL9602](INFO): SBD MO Status=1, MOMSN=44350, MT Status=0, MTMSN=0
2022-08-31T13:27:31.272Z,1661952451.272 [NAL9602](INFO): Sent 105 bytes from file Logs/20220831T131444/Express0008.lzma
2022-08-31T13:27:31.272Z,1661952451.272 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:27:35.349Z,1661952455.349 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2022-08-31T13:27:41.888Z,1661952461.888 [NAL9602](INFO): SBD MO Status=0, MOMSN=44351, MT Status=0, MTMSN=0
2022-08-31T13:28:00.451Z,1661952480.451 [NAL9602](INFO): SBD MO Status=1, MOMSN=44352, MT Status=0, MTMSN=0
2022-08-31T13:28:00.504Z,1661952480.504 [NAL9602](INFO): Sent 52 bytes from file Logs/20220831T131444/Courier0010.lzma
2022-08-31T13:28:00.505Z,1661952480.505 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:28:05.044Z,1661952485.044 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2022-08-31T13:28:09.008Z,1661952489.008 [NAL9602](INFO): SBD MO Status=1, MOMSN=44353, MT Status=0, MTMSN=0
2022-08-31T13:28:09.060Z,1661952489.060 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0011.lzma
2022-08-31T13:28:09.060Z,1661952489.060 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:28:17.247Z,1661952497.247 [NAL9602](INFO): SBD MO Status=1, MOMSN=44354, MT Status=0, MTMSN=0
2022-08-31T13:28:17.313Z,1661952497.313 [NAL9602](INFO): Sent 8 bytes from file Logs/20220831T131444/Express0011.lzma
2022-08-31T13:28:17.313Z,1661952497.313 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:28:22.688Z,1661952502.688 [NAL9602](INFO): SBD MO Status=0, MOMSN=44355, MT Status=0, MTMSN=0
2022-08-31T13:28:22.780Z,1661952502.780 [Default:CheckIn:Read_Iridium] Stopped
2022-08-31T13:28:22.780Z,1661952502.780 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-31T13:28:22.780Z,1661952502.780 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-31T13:28:27.127Z,1661952507.127 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2022-08-31T13:28:51.036Z,1661952531.036 [CommandExec](IMPORTANT): got command ibit
2022-08-31T13:28:51.057Z,1661952531.057 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-08-31T13:28:51.058Z,1661952531.058 [IBIT](IMPORTANT): Beginning control surface checks.
2022-08-31T13:28:51.072Z,1661952531.072 [CBIT](IMPORTANT): Beginning ground fault scan
2022-08-31T13:28:52.582Z,1661952532.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132859.00,A,4014.97025,N,07114.72579,W,0.661,98.73,310822,,,A*43
2022-08-31T13:28:52.585Z,1661952532.585 [NAL9602](INFO): GPS fix at 20220831T132859: (40.249504, -71.245430)
2022-08-31T13:28:55.806Z,1661952535.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132902.00,A,4014.97004,N,07114.72480,W,1.108,99.64,310822,,,A*46
2022-08-31T13:28:55.821Z,1661952535.821 [NAL9602](INFO): GPS fix at 20220831T132902: (40.249501, -71.245413)
2022-08-31T13:28:58.912Z,1661952538.912 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132905.00,A,4014.96984,N,07114.72390,W,0.991,93.78,310822,,,A*49
2022-08-31T13:28:58.914Z,1661952538.914 [NAL9602](INFO): GPS fix at 20220831T132905: (40.249497, -71.245398)
2022-08-31T13:29:01.744Z,1661952541.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132908.00,A,4014.96965,N,07114.72315,W,0.544,93.78,310822,,,A*42
2022-08-31T13:29:01.746Z,1661952541.746 [NAL9602](INFO): GPS fix at 20220831T132908: (40.249494, -71.245386)
2022-08-31T13:29:02.214Z,1661952542.214 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.026901
CHAN A1 (24V): 0.031602
CHAN A2 (12V): 0.006222
CHAN A3 (5V): 0.001060
CHAN B0 (3.3V): 0.000578
CHAN B1 (3.15aV): 0.000546
CHAN B2 (3.15bV): 0.000548
CHAN B3 (GND): -0.002331
OPEN: 0.006194
Full Scale: +/- 1 mA
2022-08-31T13:29:04.571Z,1661952544.571 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132911.00,A,4014.96941,N,07114.72224,W,0.933,93.04,310822,,,D*4D
2022-08-31T13:29:04.573Z,1661952544.573 [NAL9602](INFO): GPS fix at 20220831T132911: (40.249490, -71.245371)
2022-08-31T13:29:07.804Z,1661952547.804 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132914.00,A,4014.96917,N,07114.72138,W,1.089,103.86,310822,,,D*7E
2022-08-31T13:29:07.806Z,1661952547.806 [NAL9602](INFO): GPS fix at 20220831T132914: (40.249486, -71.245356)
2022-08-31T13:29:10.626Z,1661952550.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132917.00,A,4014.96892,N,07114.72033,W,0.875,101.93,310822,,,D*77
2022-08-31T13:29:10.639Z,1661952550.639 [NAL9602](INFO): GPS fix at 20220831T132917: (40.249482, -71.245339)
2022-08-31T13:29:13.861Z,1661952553.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132920.00,A,4014.96872,N,07114.71965,W,0.816,101.93,310822,,,D*71
2022-08-31T13:29:13.863Z,1661952553.863 [NAL9602](INFO): GPS fix at 20220831T132920: (40.249479, -71.245328)
2022-08-31T13:29:16.693Z,1661952556.693 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132923.00,A,4014.96864,N,07114.71889,W,0.952,103.53,310822,,,D*79
2022-08-31T13:29:16.695Z,1661952556.695 [NAL9602](INFO): GPS fix at 20220831T132923: (40.249477, -71.245315)
2022-08-31T13:29:19.919Z,1661952559.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132926.00,A,4014.96836,N,07114.71776,W,0.894,101.01,310822,,,D*7A
2022-08-31T13:29:19.921Z,1661952559.921 [NAL9602](INFO): GPS fix at 20220831T132926: (40.249473, -71.245296)
2022-08-31T13:29:22.751Z,1661952562.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132929.00,A,4014.96822,N,07114.71691,W,0.797,101.01,310822,,,D*74
2022-08-31T13:29:22.753Z,1661952562.753 [NAL9602](INFO): GPS fix at 20220831T132929: (40.249470, -71.245282)
2022-08-31T13:29:25.228Z,1661952565.228 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2022-08-31T13:29:25.574Z,1661952565.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132932.00,A,4014.96804,N,07114.71583,W,0.797,101.01,310822,,,D*7A
2022-08-31T13:29:25.592Z,1661952565.592 [NAL9602](INFO): GPS fix at 20220831T132932: (40.249467, -71.245264)
2022-08-31T13:29:28.815Z,1661952568.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132935.00,A,4014.96797,N,07114.71493,W,1.050,99.98,310822,,,D*45
2022-08-31T13:29:28.818Z,1661952568.818 [NAL9602](INFO): GPS fix at 20220831T132935: (40.249466, -71.245249)
2022-08-31T13:29:31.660Z,1661952571.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132938.00,A,4014.96783,N,07114.71402,W,0.875,98.57,310822,,,D*49
2022-08-31T13:29:31.662Z,1661952571.662 [NAL9602](INFO): GPS fix at 20220831T132938: (40.249464, -71.245234)
2022-08-31T13:29:34.871Z,1661952574.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132941.00,A,4014.96771,N,07114.71308,W,0.758,98.57,310822,,,D*47
2022-08-31T13:29:34.873Z,1661952574.873 [NAL9602](INFO): GPS fix at 20220831T132941: (40.249462, -71.245218)
2022-08-31T13:29:35.676Z,1661952575.676 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:29:35.736Z,1661952575.736 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831132936.183457,06,742,22,0149,0150,208,02,02,01,02,-1,-01,-01,1,3,2,0,150,11.0,-0.10,-100,3.10,-01,-2.40,63,14500,4000*4D

2022-08-31T13:29:37.371Z,1661952577.371 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 40.249462 Longitude: -71.245216
2022-08-31T13:29:37.697Z,1661952577.697 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,132944.00,A,4014.96749,N,07114.71231,W,0.914,98.57,310822,,,D*44
2022-08-31T13:29:37.699Z,1661952577.699 [NAL9602](INFO): GPS fix at 20220831T132944: (40.249458, -71.245205)
2022-08-31T13:29:37.717Z,1661952577.717 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2022-08-31T13:29:37.717Z,1661952577.717 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-08-31T13:29:37.717Z,1661952577.717 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-08-31T13:29:38.161Z,1661952578.161 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-08-31T13:29:38.162Z,1661952578.162 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-08-31T13:29:38.162Z,1661952578.162 [IBIT](IMPORTANT): Pressure:7.651849 PSI
2022-08-31T13:29:38.162Z,1661952578.162 [IBIT](IMPORTANT): Humidity:24.124039 %
2022-08-31T13:29:38.525Z,1661952578.525 [IBIT](IMPORTANT): Vehicle Pitch:1.061089 degrees
2022-08-31T13:29:38.525Z,1661952578.525 [IBIT](IMPORTANT): Vehicle  Roll:3.975611 degrees
2022-08-31T13:29:38.525Z,1661952578.525 [IBIT](IMPORTANT): Vehicle  Heading:354.624390 degrees
2022-08-31T13:29:38.971Z,1661952578.971 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-08-31T13:29:38.977Z,1661952578.977 [IBIT](IMPORTANT): buoyancyNeutral: 411.225220 cc
2022-08-31T13:29:38.978Z,1661952578.978 [IBIT](IMPORTANT): massDefault: -0.574058 cm
2022-08-31T13:29:38.978Z,1661952578.978 [IBIT](IMPORTANT): stopDepth: 35.000000 m
2022-08-31T13:29:38.984Z,1661952578.984 [IBIT](IMPORTANT): abortDepth: 50.000000 m
2022-08-31T13:29:38.984Z,1661952578.984 [IBIT](IMPORTANT): IBIT PASSED
2022-08-31T13:29:40.136Z,1661952580.136 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2022-08-31T13:30:10.428Z,1661952610.428 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-31T13:33:23.173Z,1661952803.173 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-31T13:33:23.173Z,1661952803.173 [Default:CheckIn:C.Wait] Stopped
2022-08-31T13:33:23.173Z,1661952803.173 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-31T13:33:23.173Z,1661952803.173 [Default:CheckIn:D] Running Loop=1
2022-08-31T13:33:23.555Z,1661952803.555 [Default:CheckIn:D] Stopped
2022-08-31T13:33:23.556Z,1661952803.556 [Default:CheckIn:E] Running Loop=1
2022-08-31T13:33:23.948Z,1661952803.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.833765 min 
2022-08-31T13:33:23.948Z,1661952803.948 [Default:CheckIn:E] Stopped
2022-08-31T13:33:23.948Z,1661952803.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-31T13:33:23.948Z,1661952803.948 [Default:CheckIn] Stopped
2022-08-31T13:33:23.948Z,1661952803.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-31T13:33:23.948Z,1661952803.948 [Default:CheckIn](INFO): Running loop #3
2022-08-31T13:33:23.949Z,1661952803.949 [Default:CheckIn] Running Loop=3
2022-08-31T13:33:23.949Z,1661952803.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-31T13:33:23.949Z,1661952803.949 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-31T13:33:25.961Z,1661952805.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,133332.00,A,4014.88874,N,07114.68232,W,2.391,201.49,310822,,,D*73
2022-08-31T13:33:25.963Z,1661952805.963 [NAL9602](INFO): GPS fix at 20220831T133332: (40.248146, -71.244705)
2022-08-31T13:33:25.974Z,1661952805.974 [Default:CheckIn:Read_GPS] Stopped
2022-08-31T13:33:25.974Z,1661952805.974 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-31T13:33:42.740Z,1661952822.740 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=44356, MT Status=1, MTMSN=1682
2022-08-31T13:33:42.796Z,1661952822.796 [NAL9602](INFO): Sent 72 bytes from file Logs/20220831T131444/Courier0013.lzma
2022-08-31T13:33:42.796Z,1661952822.796 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:33:43.276Z,1661952823.276 [NAL9602](INFO): Received command: configSet DockingServo.loadAtStartup 0 bool persist
2022-08-31T13:33:43.690Z,1661952823.690 [CommandExec](IMPORTANT): got command configSet DockingServo.loadAtStartup 0 bool persist
2022-08-31T13:33:43.690Z,1661952823.690 [CommandExec](IMPORTANT): configSet DockingServo.loadAtStartup requires a restart to take effect.
2022-08-31T13:33:55.366Z,1661952835.366 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2022-08-31T13:34:11.850Z,1661952851.850 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:34:12.257Z,1661952852.257 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831133412.187877,06,678,22,0132,0150,208,02,02,01,02,-1,-01,-01,1,3,7,0,150,11.2,-0.03,-100,3.03,-01,-1.64,63,14500,4000*4B

2022-08-31T13:34:13.073Z,1661952853.073 [NAL9602](INFO): SBD MO Status=1, MOMSN=44357, MT Status=0, MTMSN=0
2022-08-31T13:34:13.121Z,1661952853.121 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0014.lzma
2022-08-31T13:34:13.122Z,1661952853.122 [NAL9602](INFO): Packets left to send: 3
2022-08-31T13:34:26.828Z,1661952866.828 [NAL9602](INFO): SBD MO Status=2, MOMSN=44358, MT Status=2, MTMSN=0
2022-08-31T13:34:26.828Z,1661952866.828 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-31T13:34:53.451Z,1661952893.451 [NAL9602](INFO): SBD MO Status=2, MOMSN=44358, MT Status=2, MTMSN=0
2022-08-31T13:34:53.451Z,1661952893.451 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-08-31T13:35:07.667Z,1661952907.667 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:35:08.067Z,1661952908.067 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831133508.189886,06,623,21,0229,0150,208,02,02,01,02,-1,-01,-01,1,3,7,0,150,10.1,0.17,-100,2.83,-01,-1.20,64,14500,4000*61

2022-08-31T13:35:14.204Z,1661952914.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=44358, MT Status=0, MTMSN=0
2022-08-31T13:35:14.256Z,1661952914.256 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0014.lzma
2022-08-31T13:35:14.256Z,1661952914.256 [NAL9602](INFO): Packets left to send: 2
2022-08-31T13:35:32.359Z,1661952932.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=44359, MT Status=0, MTMSN=0
2022-08-31T13:35:32.408Z,1661952932.408 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0014.lzma
2022-08-31T13:35:32.408Z,1661952932.408 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:35:44.119Z,1661952944.119 [NAL9602](INFO): SBD MO Status=1, MOMSN=44360, MT Status=0, MTMSN=0
2022-08-31T13:35:44.164Z,1661952944.164 [NAL9602](INFO): Sent 178 bytes from file Logs/20220831T131444/Express0014.lzma
2022-08-31T13:35:44.164Z,1661952944.164 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:35:52.147Z,1661952952.147 [NAL9602](INFO): SBD MO Status=0, MOMSN=44361, MT Status=0, MTMSN=0
2022-08-31T13:36:09.889Z,1661952969.889 [NAL9602](INFO): SBD MO Status=1, MOMSN=44362, MT Status=0, MTMSN=0
2022-08-31T13:36:09.940Z,1661952969.940 [NAL9602](INFO): Sent 52 bytes from file Logs/20220831T131444/Courier0016.lzma
2022-08-31T13:36:09.940Z,1661952969.940 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:36:18.871Z,1661952978.871 [NAL9602](INFO): SBD MO Status=1, MOMSN=44363, MT Status=0, MTMSN=0
2022-08-31T13:36:18.928Z,1661952978.928 [NAL9602](INFO): Sent 184 bytes from file Logs/20220831T131444/Express0017.lzma
2022-08-31T13:36:18.928Z,1661952978.928 [NAL9602](INFO): Packets left to send: 1
2022-08-31T13:36:27.016Z,1661952987.016 [NAL9602](INFO): SBD MO Status=1, MOMSN=44364, MT Status=0, MTMSN=0
2022-08-31T13:36:27.064Z,1661952987.064 [NAL9602](INFO): Sent 24 bytes from file Logs/20220831T131444/Express0017.lzma
2022-08-31T13:36:27.064Z,1661952987.064 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:36:35.239Z,1661952995.239 [NAL9602](INFO): SBD MO Status=0, MOMSN=44365, MT Status=0, MTMSN=0
2022-08-31T13:36:35.356Z,1661952995.356 [Default:CheckIn:Read_Iridium] Stopped
2022-08-31T13:36:35.356Z,1661952995.356 [Default:CheckIn:C.Wait] Running Loop=1
2022-08-31T13:36:35.356Z,1661952995.356 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-08-31T13:36:59.480Z,1661953019.480 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-08-31T13:36:59.884Z,1661953019.884 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831133658.188827,06,1378,25,0126,0150,208,02,02,01,02,4,-01,-01,2,3,1,0,150,1.7,-0.78,-100,3.78,-01,-4.14,62,14500,4000*61

2022-08-31T13:37:05.937Z,1661953025.937 [NAL9602](INFO): Not Powering down - fast GPS
2022-08-31T13:37:37.861Z,1661953057.861 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:37:37.865Z,1661953057.865 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831133738.195421,06,1431,25,0117,0150,208,02,02,01,02,-1,-01,-01,1,3,4,0,150,10.4,0.04,-100,2.96,-01,-4.21,63,14500,4000*55

2022-08-31T13:39:49.561Z,1661953189.561 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:39:49.960Z,1661953189.960 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831133950.172829,06,263,24,0090,0150,223,02,02,00,02,-1,-01,-01,1,3,3,0,150,9.0,0.02,-100,2.98,-01,-2.18,64,14500,4000*57

2022-08-31T13:39:53.607Z,1661953193.607 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-08-31T13:39:54.010Z,1661953194.010 [Micromodem](INFO): Nmea in: $CACST,6,1,20220831133954.173416,06,1042,24,0091,0150,208,02,02,01,02,-1,-01,-01,1,3,6,0,150,11.2,0.11,-100,2.89,-01,0.38,62,14500,4000*7C

2022-08-31T13:41:35.842Z,1661953295.842 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-08-31T13:41:35.842Z,1661953295.842 [Default:CheckIn:C.Wait] Stopped
2022-08-31T13:41:35.843Z,1661953295.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-08-31T13:41:35.843Z,1661953295.843 [Default:CheckIn:D] Running Loop=1
2022-08-31T13:41:36.224Z,1661953296.224 [Default:CheckIn:D] Stopped
2022-08-31T13:41:36.224Z,1661953296.224 [Default:CheckIn:E] Running Loop=1
2022-08-31T13:41:36.632Z,1661953296.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.044908 min 
2022-08-31T13:41:36.632Z,1661953296.632 [Default:CheckIn:E] Stopped
2022-08-31T13:41:36.632Z,1661953296.632 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-08-31T13:41:36.632Z,1661953296.632 [Default:CheckIn] Stopped
2022-08-31T13:41:36.632Z,1661953296.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-31T13:41:36.633Z,1661953296.633 [Default:CheckIn](INFO): Running loop #4
2022-08-31T13:41:36.633Z,1661953296.633 [Default:CheckIn] Running Loop=4
2022-08-31T13:41:36.633Z,1661953296.633 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-08-31T13:41:36.633Z,1661953296.633 [Default:CheckIn:Read_GPS] Running Loop=1
2022-08-31T13:41:38.634Z,1661953298.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134145.00,A,4014.59391,N,07114.95490,W,1.963,255.95,310822,,,A*77
2022-08-31T13:41:38.646Z,1661953298.646 [NAL9602](INFO): GPS fix at 20220831T134145: (40.243232, -71.249248)
2022-08-31T13:41:38.658Z,1661953298.658 [Default:CheckIn:Read_GPS] Stopped
2022-08-31T13:41:38.658Z,1661953298.658 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-08-31T13:41:45.917Z,1661953305.917 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2022-08-31T13:42:01.863Z,1661953321.863 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=44366, MT Status=1, MTMSN=1683
2022-08-31T13:42:01.916Z,1661953321.916 [NAL9602](INFO): Sent 72 bytes from file Logs/20220831T131444/Courier0019.lzma
2022-08-31T13:42:01.916Z,1661953321.916 [NAL9602](INFO): Packets left to send: 0
2022-08-31T13:42:02.364Z,1661953322.364 [NAL9602](INFO): Received command: restart app
2022-08-31T13:42:02.409Z,1661953322.409 [CommandExec](IMPORTANT): got command restart application
2022-08-31T13:42:03.411Z,1661953323.411 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-08-31T13:42:03.412Z,1661953323.412 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:03.412Z,1661953323.412 [CommandExec](INFO): Uninitializing the command executive.
2022-08-31T13:42:03.412Z,1661953323.412 [CommandExec](INFO): Uninitializing the command scheduler.
2022-08-31T13:42:03.412Z,1661953323.412 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:03.567Z,1661953323.567 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-08-31T13:42:03.567Z,1661953323.567 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-08-31T13:42:03.568Z,1661953323.568 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:03.568Z,1661953323.568 [NavChartDb](INFO): Join timeout helper Thread ID is 8824
2022-08-31T13:42:03.911Z,1661953323.911 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:03.912Z,1661953323.912 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:03.916Z,1661953323.916 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2022-08-31T13:42:03.916Z,1661953323.916 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:03.916Z,1661953323.916 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8825
2022-08-31T13:42:03.996Z,1661953323.996 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:03.996Z,1661953323.996 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:04.010Z,1661953324.010 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-08-31T13:42:04.010Z,1661953324.010 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:04.011Z,1661953324.011 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8826
2022-08-31T13:42:04.092Z,1661953324.092 [CTD_Seabird](INFO): Powering down
2022-08-31T13:42:04.108Z,1661953324.108 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:04.108Z,1661953324.108 [CTD_Seabird](INFO): Powering down
2022-08-31T13:42:04.124Z,1661953324.124 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:04.125Z,1661953324.125 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-08-31T13:42:04.125Z,1661953324.125 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:04.126Z,1661953324.126 [Radio_Surface](INFO): Join timeout helper Thread ID is 8827
2022-08-31T13:42:04.411Z,1661953324.411 [Radio_Surface](INFO): Powering down
2022-08-31T13:42:04.412Z,1661953324.412 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:04.413Z,1661953324.413 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:04.423Z,1661953324.423 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-08-31T13:42:04.423Z,1661953324.423 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:04.424Z,1661953324.424 [Onboard](INFO): Join timeout helper Thread ID is 8828
2022-08-31T13:42:05.696Z,1661953325.696 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:05.696Z,1661953325.696 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:05.708Z,1661953325.708 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-08-31T13:42:05.708Z,1661953325.708 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:05.709Z,1661953325.709 [DataOverHttps](INFO): Join timeout helper Thread ID is 8829
2022-08-31T13:42:06.048Z,1661953326.048 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:06.048Z,1661953326.048 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.060Z,1661953326.060 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-08-31T13:42:06.060Z,1661953326.060 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.061Z,1661953326.061 [logger](INFO): Join timeout helper Thread ID is 8830
2022-08-31T13:42:06.152Z,1661953326.152 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:06.153Z,1661953326.153 [logger ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.172Z,1661953326.172 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-08-31T13:42:06.172Z,1661953326.172 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.173Z,1661953326.173 [CommandLine](INFO): Join timeout helper Thread ID is 8831
2022-08-31T13:42:06.228Z,1661953326.228 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:06.228Z,1661953326.228 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.232Z,1661953326.232 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-08-31T13:42:06.232Z,1661953326.232 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.233Z,1661953326.233 [CommandExec](INFO): Join timeout helper Thread ID is 8832
2022-08-31T13:42:06.234Z,1661953326.234 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-08-31T13:42:06.234Z,1661953326.234 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.234Z,1661953326.234 [controlThread](INFO): Join timeout helper Thread ID is 8833
2022-08-31T13:42:06.285Z,1661953326.285 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-08-31T13:42:06.286Z,1661953326.286 [controlThread](DEBUG): Uninitializing ControlThread
2022-08-31T13:42:06.288Z,1661953326.288 [AHRS_M2](INFO): Powering down
2022-08-31T13:42:06.360Z,1661953326.360 [DDM](INFO): Powering down
2022-08-31T13:42:06.432Z,1661953326.432 [Micromodem](INFO): Powering down
2022-08-31T13:42:06.528Z,1661953326.528 [NAL9602](INFO): Powering down
2022-08-31T13:42:06.599Z,1661953326.599 [RDI_Pathfinder](INFO): Powering down
2022-08-31T13:42:06.671Z,1661953326.671 [RDI_PathfinderUp](INFO): Powering down
2022-08-31T13:42:06.673Z,1661953326.673 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-08-31T13:42:06.674Z,1661953326.674 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-08-31T13:42:06.674Z,1661953326.674 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-08-31T13:42:06.675Z,1661953326.675 [MissionManager](INFO): Uninitializing Mission Default
2022-08-31T13:42:06.676Z,1661953326.676 [Default] Stopped
2022-08-31T13:42:06.676Z,1661953326.676 [Default](DEBUG): Aggregate::uninitialize Default
2022-08-31T13:42:06.676Z,1661953326.676 [Default:B.GoToSurface] Stopped
2022-08-31T13:42:06.676Z,1661953326.676 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-08-31T13:42:06.676Z,1661953326.676 [Default:CheckIn] Stopped
2022-08-31T13:42:06.676Z,1661953326.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-08-31T13:42:06.676Z,1661953326.676 [Default:CheckIn:Read_Iridium] Stopped
2022-08-31T13:42:06.679Z,1661953326.679 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-08-31T13:42:06.679Z,1661953326.679 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-08-31T13:42:06.679Z,1661953326.679 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-08-31T13:42:06.680Z,1661953326.680 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-08-31T13:42:06.680Z,1661953326.680 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-08-31T13:42:06.680Z,1661953326.680 [BuoyancyServo](INFO): Powering down
2022-08-31T13:42:06.692Z,1661953326.692 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-08-31T13:42:06.692Z,1661953326.692 [ElevatorServo](INFO): Powering down
2022-08-31T13:42:06.693Z,1661953326.693 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-08-31T13:42:06.693Z,1661953326.693 [MassServo](INFO): Powering down
2022-08-31T13:42:06.694Z,1661953326.694 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-08-31T13:42:06.694Z,1661953326.694 [RudderServo](INFO): Powering down
2022-08-31T13:42:06.694Z,1661953326.694 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-08-31T13:42:06.695Z,1661953326.695 [ThrusterHE](INFO): Powering down
2022-08-31T13:42:06.696Z,1661953326.696 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-08-31T13:42:06.696Z,1661953326.696 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-08-31T13:42:06.696Z,1661953326.696 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-08-31T13:42:06.696Z,1661953326.696 [CBIT](DEBUG): Powering off loads.
2022-08-31T13:42:06.707Z,1661953326.707 [CBIT](DEBUG): Disabling WDT.
2022-08-31T13:42:06.719Z,1661953326.719 [CBIT](DEBUG): Opening all GF detection circuits.
2022-08-31T13:42:06.720Z,1661953326.720 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.736Z,1661953326.736 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.739Z,1661953326.739 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:06.749Z,1661953326.749 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:07.064Z,1661953327.064 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:07.072Z,1661953327.072 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:07.163Z,1661953327.163 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-08-31T13:42:07.290Z,1661953327.290 [logger ThreadHandler](INFO): Thread cancelled.