2022-01-20T14:05:46.776Z,1642687546.776 [Supervisor](DEBUG): Initializing supervisor.
2022-01-20T14:05:46.780Z,1642687546.780 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-01-20T14:05:46.781Z,1642687546.781 [SyncHandler](INFO): Protected caller Thread ID is 1505
2022-01-20T14:05:46.781Z,1642687546.781 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-01-20T14:05:46.782Z,1642687546.782 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-01-20T14:05:46.783Z,1642687546.783 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1506
2022-01-20T14:05:46.787Z,1642687546.787 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-01-20T14:05:46.805Z,1642687546.805 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-01-20T14:05:46.806Z,1642687546.806 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-01-20T14:05:46.807Z,1642687546.807 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1507
2022-01-20T14:05:46.811Z,1642687546.811 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-01-20T14:05:46.812Z,1642687546.812 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-01-20T14:05:46.812Z,1642687546.812 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1508
2022-01-20T14:05:46.814Z,1642687546.814 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-01-20T14:05:46.815Z,1642687546.815 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-01-20T14:05:46.816Z,1642687546.816 [logger ThreadHandler](INFO): Protected caller Thread ID is 1509
2022-01-20T14:05:46.819Z,1642687546.819 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-01-20T14:05:46.820Z,1642687546.820 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-01-20T14:05:46.825Z,1642687546.825 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-01-20T14:05:47.321Z,1642687547.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-01-20T14:05:47.323Z,1642687547.323 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-01-20T14:05:47.405Z,1642687547.405 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-01-20T14:05:47.740Z,1642687547.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-01-20T14:05:47.741Z,1642687547.741 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-01-20T14:05:47.851Z,1642687547.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-01-20T14:05:47.852Z,1642687547.852 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-01-20T14:05:48.060Z,1642687548.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-01-20T14:05:48.062Z,1642687548.062 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-01-20T14:05:48.587Z,1642687548.587 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-01-20T14:05:48.589Z,1642687548.589 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-01-20T14:05:48.861Z,1642687548.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-01-20T14:05:48.862Z,1642687548.862 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-01-20T14:05:49.012Z,1642687549.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-01-20T14:05:49.014Z,1642687549.014 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-01-20T14:05:49.211Z,1642687549.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-01-20T14:05:49.213Z,1642687549.213 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-01-20T14:05:49.312Z,1642687549.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-01-20T14:05:49.313Z,1642687549.313 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-01-20T14:05:49.572Z,1642687549.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-01-20T14:05:49.845Z,1642687549.845 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-01-20T14:05:50.402Z,1642687550.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-01-20T14:05:50.402Z,1642687550.402 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-01-20T14:05:50.485Z,1642687550.485 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-01-20T14:05:50.613Z,1642687550.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-01-20T14:05:50.615Z,1642687550.615 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-01-20T14:05:51.324Z,1642687551.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-01-20T14:05:51.325Z,1642687551.325 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-01-20T14:05:51.695Z,1642687551.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-01-20T14:05:51.697Z,1642687551.697 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-polaris/
2022-01-20T14:05:51.700Z,1642687551.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/vehicle.cfg
2022-01-20T14:05:51.904Z,1642687551.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Control.cfg
2022-01-20T14:05:52.006Z,1642687552.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Estimation.cfg
2022-01-20T14:05:52.107Z,1642687552.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/BIT.cfg
2022-01-20T14:05:52.208Z,1642687552.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Battery.cfg
2022-01-20T14:05:52.473Z,1642687552.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-01-20T14:05:52.475Z,1642687552.475 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Simulator.cfg
2022-01-20T14:05:52.562Z,1642687552.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Navigation.cfg
2022-01-20T14:05:52.666Z,1642687552.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Derivation.cfg
2022-01-20T14:05:52.794Z,1642687552.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/logger.cfg
2022-01-20T14:05:52.909Z,1642687552.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/secure.cfg
2022-01-20T14:05:52.995Z,1642687552.995 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Dock.cfg
2022-01-20T14:05:53.083Z,1642687553.083 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Servo.cfg
2022-01-20T14:05:53.197Z,1642687553.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/workSite.cfg
2022-01-20T14:05:53.298Z,1642687553.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Sensor.cfg
2022-01-20T14:05:53.511Z,1642687553.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-polaris/Science.cfg
2022-01-20T14:05:53.678Z,1642687553.678 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-01-20T14:05:53.688Z,1642687553.688 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-01-20T14:05:54.243Z,1642687554.243 [AHRS_M2] Loaded
2022-01-20T14:05:54.243Z,1642687554.243 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-01-20T14:05:54.277Z,1642687554.277 [BackseatComponent] Loaded
2022-01-20T14:05:54.277Z,1642687554.277 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-01-20T14:05:54.278Z,1642687554.278 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 407DF4E0
2022-01-20T14:05:54.279Z,1642687554.279 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1603
2022-01-20T14:05:54.281Z,1642687554.281 [LcmUniversalReporter] Loaded
2022-01-20T14:05:54.282Z,1642687554.282 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-01-20T14:05:55.663Z,1642687555.663 [BPC1] Loaded
2022-01-20T14:05:55.664Z,1642687555.664 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-01-20T14:05:55.735Z,1642687555.735 [DataOverHttps] Loaded
2022-01-20T14:05:55.736Z,1642687555.736 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-01-20T14:05:55.737Z,1642687555.737 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4080F4E0
2022-01-20T14:05:55.737Z,1642687555.737 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1604
2022-01-20T14:05:55.756Z,1642687555.756 [Depth_Keller] Loaded
2022-01-20T14:05:55.756Z,1642687555.756 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-01-20T14:05:55.761Z,1642687555.761 [DropWeight] Loaded
2022-01-20T14:05:55.761Z,1642687555.761 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-01-20T14:05:55.809Z,1642687555.809 [Micromodem] Loaded
2022-01-20T14:05:55.809Z,1642687555.809 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2022-01-20T14:05:55.868Z,1642687555.868 [NAL9602] Loaded
2022-01-20T14:05:55.868Z,1642687555.868 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-01-20T14:05:55.896Z,1642687555.896 [Onboard] Loaded
2022-01-20T14:05:55.896Z,1642687555.896 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-01-20T14:05:55.897Z,1642687555.897 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4083F4E0
2022-01-20T14:05:55.897Z,1642687555.897 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1605
2022-01-20T14:05:55.903Z,1642687555.903 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2022-01-20T14:05:55.913Z,1642687555.913 [PowerOnly] Loaded
2022-01-20T14:05:55.914Z,1642687555.914 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2022-01-20T14:05:55.925Z,1642687555.925 [Power24vConverter] Loaded
2022-01-20T14:05:55.925Z,1642687555.925 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-01-20T14:05:55.939Z,1642687555.939 [Radio_Surface] Loaded
2022-01-20T14:05:55.939Z,1642687555.939 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-01-20T14:05:55.940Z,1642687555.940 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4086F4E0
2022-01-20T14:05:55.940Z,1642687555.940 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1606
2022-01-20T14:05:55.983Z,1642687555.983 [RDI_Pathfinder] Loaded
2022-01-20T14:05:55.983Z,1642687555.983 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2022-01-20T14:05:56.009Z,1642687556.009 [RDI_PathfinderUp] Loaded
2022-01-20T14:05:56.009Z,1642687556.009 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2022-01-20T14:05:56.010Z,1642687556.010 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-01-20T14:05:56.011Z,1642687556.011 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-01-20T14:05:56.026Z,1642687556.026 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-01-20T14:05:56.027Z,1642687556.027 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-01-20T14:05:56.157Z,1642687556.157 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-01-20T14:05:56.157Z,1642687556.157 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-01-20T14:05:56.181Z,1642687556.181 [LBLNavigation] Loaded
2022-01-20T14:05:56.182Z,1642687556.182 [ComponentRegistry](DEBUG): SyncComponent "LBLNavigation" handled in the control thread.
2022-01-20T14:05:56.194Z,1642687556.194 [NavChart] Loaded
2022-01-20T14:05:56.195Z,1642687556.195 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-01-20T14:05:56.200Z,1642687556.200 [UniversalFixResidualReporter] Loaded
2022-01-20T14:05:56.201Z,1642687556.201 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-01-20T14:05:56.211Z,1642687556.211 [WorkSite] Loaded
2022-01-20T14:05:56.212Z,1642687556.212 [ComponentRegistry](DEBUG): SyncComponent "WorkSite" handled in the control thread.
2022-01-20T14:05:56.212Z,1642687556.212 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-01-20T14:05:56.212Z,1642687556.212 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-01-20T14:05:56.329Z,1642687556.329 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-01-20T14:05:56.329Z,1642687556.329 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-01-20T14:05:56.480Z,1642687556.480 [BuoyancyServo] Loaded
2022-01-20T14:05:56.481Z,1642687556.481 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-01-20T14:05:56.502Z,1642687556.502 [ElevatorServo] Loaded
2022-01-20T14:05:56.502Z,1642687556.502 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-01-20T14:05:56.523Z,1642687556.523 [MassServo] Loaded
2022-01-20T14:05:56.523Z,1642687556.523 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-01-20T14:05:56.543Z,1642687556.543 [RudderServo] Loaded
2022-01-20T14:05:56.544Z,1642687556.544 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-01-20T14:05:56.560Z,1642687556.560 [ThrusterHE] Loaded
2022-01-20T14:05:56.560Z,1642687556.560 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-01-20T14:05:56.561Z,1642687556.561 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-01-20T14:05:56.561Z,1642687556.561 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-01-20T14:05:56.771Z,1642687556.771 [CTD_Seabird] Loaded
2022-01-20T14:05:56.772Z,1642687556.772 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-01-20T14:05:56.773Z,1642687556.773 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2022-01-20T14:05:56.773Z,1642687556.773 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1607
2022-01-20T14:05:56.793Z,1642687556.793 [PAR_Licor] Loaded
2022-01-20T14:05:56.793Z,1642687556.793 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-01-20T14:05:56.826Z,1642687556.826 [WetLabsSeaOWL_UV_A] Loaded
2022-01-20T14:05:56.826Z,1642687556.826 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2022-01-20T14:05:56.827Z,1642687556.827 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-01-20T14:05:56.827Z,1642687556.827 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1608
2022-01-20T14:05:56.828Z,1642687556.828 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-01-20T14:05:56.829Z,1642687556.829 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-01-20T14:05:57.197Z,1642687557.197 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-01-20T14:05:57.198Z,1642687557.198 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-01-20T14:05:57.256Z,1642687557.256 [DepthRateCalculator] Loaded
2022-01-20T14:05:57.256Z,1642687557.256 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-01-20T14:05:57.261Z,1642687557.261 [PitchRateCalculator] Loaded
2022-01-20T14:05:57.262Z,1642687557.262 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-01-20T14:05:57.271Z,1642687557.271 [SpeedCalculator] Loaded
2022-01-20T14:05:57.271Z,1642687557.271 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-01-20T14:05:57.284Z,1642687557.284 [TempGradientCalculator] Loaded
2022-01-20T14:05:57.285Z,1642687557.285 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-01-20T14:05:57.300Z,1642687557.300 [VerticalHomogeneityIndexCalculator] Loaded
2022-01-20T14:05:57.300Z,1642687557.300 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2022-01-20T14:05:57.305Z,1642687557.305 [YawRateCalculator] Loaded
2022-01-20T14:05:57.305Z,1642687557.305 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-01-20T14:05:57.327Z,1642687557.327 [ElevatorOffsetCalculator] Loaded
2022-01-20T14:05:57.327Z,1642687557.327 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-01-20T14:05:57.327Z,1642687557.327 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-01-20T14:05:57.328Z,1642687557.328 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-01-20T14:05:57.502Z,1642687557.502 [SBIT](DEBUG): Construct Startup Built In Test.
2022-01-20T14:05:57.514Z,1642687557.514 [SBIT] Loaded
2022-01-20T14:05:57.514Z,1642687557.514 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-01-20T14:05:57.517Z,1642687557.517 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-01-20T14:05:57.530Z,1642687557.530 [IBIT] Loaded
2022-01-20T14:05:57.531Z,1642687557.531 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-01-20T14:05:57.536Z,1642687557.536 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-01-20T14:05:57.641Z,1642687557.641 [CBIT] Loaded
2022-01-20T14:05:57.641Z,1642687557.641 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-01-20T14:05:57.642Z,1642687557.642 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-01-20T14:05:57.642Z,1642687557.642 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-01-20T14:05:57.722Z,1642687557.722 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-01-20T14:05:57.722Z,1642687557.722 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-01-20T14:05:57.853Z,1642687557.853 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-01-20T14:05:57.854Z,1642687557.854 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-01-20T14:05:57.919Z,1642687557.919 [VerticalControl](DEBUG): Construct VerticalControl.
2022-01-20T14:05:57.971Z,1642687557.971 [VerticalControl] Loaded
2022-01-20T14:05:57.972Z,1642687557.972 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-01-20T14:05:57.974Z,1642687557.974 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-01-20T14:05:58.011Z,1642687558.011 [HorizontalControl] Loaded
2022-01-20T14:05:58.011Z,1642687558.011 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-01-20T14:05:58.014Z,1642687558.014 [SpeedControl](DEBUG): Construct SpeedControl.
2022-01-20T14:05:58.016Z,1642687558.016 [SpeedControl] Loaded
2022-01-20T14:05:58.016Z,1642687558.016 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-01-20T14:05:58.019Z,1642687558.019 [LoopControl](DEBUG): Construct LoopControl.
2022-01-20T14:05:58.019Z,1642687558.019 [LoopControl] Loaded
2022-01-20T14:05:58.019Z,1642687558.019 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-01-20T14:05:58.020Z,1642687558.020 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-01-20T14:05:58.020Z,1642687558.020 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-01-20T14:05:58.059Z,1642687558.059 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-01-20T14:05:58.065Z,1642687558.065 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-01-20T14:05:58.068Z,1642687558.068 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-01-20T14:05:58.079Z,1642687558.079 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-01-20T14:05:58.080Z,1642687558.080 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B8D4E0
2022-01-20T14:05:58.080Z,1642687558.080 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1609
2022-01-20T14:05:58.085Z,1642687558.085 [Supervisor](INFO): Main Thread ID is 829
2022-01-20T14:05:58.085Z,1642687558.085 [Supervisor](DEBUG): Running supervisor.
2022-01-20T14:05:58.085Z,1642687558.085 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1610
2022-01-20T14:05:58.086Z,1642687558.086 [CommandExec](INFO): Initializing the command executive.
2022-01-20T14:05:58.087Z,1642687558.087 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1611
2022-01-20T14:05:58.089Z,1642687558.089 [controlThread ThreadHandler](INFO): Handler Thread ID is 1612
2022-01-20T14:05:58.090Z,1642687558.090 [controlThread](DEBUG): Initializing ControlThread
2022-01-20T14:05:58.096Z,1642687558.096 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-01-20T14:05:58.096Z,1642687558.096 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-01-20T14:05:58.097Z,1642687558.097 [WorkSite](DEBUG): Initializing WorkSite component.
2022-01-20T14:05:58.101Z,1642687558.101 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-01-20T14:05:58.102Z,1642687558.102 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-01-20T14:05:58.102Z,1642687558.102 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-01-20T14:05:58.102Z,1642687558.102 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-01-20T14:05:58.103Z,1642687558.103 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2022-01-20T14:05:58.103Z,1642687558.103 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-01-20T14:05:58.104Z,1642687558.104 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-01-20T14:05:58.104Z,1642687558.104 [SBIT](INFO): Initialize SBIT Component.
2022-01-20T14:05:58.105Z,1642687558.105 [SBIT](IMPORTANT): git: 2022-01-19
2022-01-20T14:05:58.105Z,1642687558.105 [SBIT](INFO): git hash: 3c152841e44dce493716324b2ee7dcf988968060
2022-01-20T14:05:58.105Z,1642687558.105 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-01-20T14:05:58.107Z,1642687558.107 [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-01-20T14:05:58.108Z,1642687558.108 [SBIT](INFO): Beginning SBIT in 27.000000 seconds.
2022-01-20T14:05:58.108Z,1642687558.108 [IBIT](INFO): Initialize IBIT Component.
2022-01-20T14:05:58.109Z,1642687558.109 [CBIT](DEBUG): Initialize CBIT Component.
2022-01-20T14:05:58.110Z,1642687558.110 [logger ThreadHandler](INFO): Handler Thread ID is 1613
2022-01-20T14:05:58.121Z,1642687558.121 [CBIT](DEBUG): Initialized mux pins.
2022-01-20T14:05:58.121Z,1642687558.121 [CBIT](DEBUG): Initializing the watchdog timer.
2022-01-20T14:05:58.129Z,1642687558.129 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1614
2022-01-20T14:05:58.141Z,1642687558.141 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1615
2022-01-20T14:05:58.142Z,1642687558.142 [DataOverHttps](DEBUG): dashIP=128.128.181.226 starts with a digit so assuming it is a numeric IP
2022-01-20T14:05:58.145Z,1642687558.145 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-01-20T14:05:58.146Z,1642687558.146 [CBIT](DEBUG): Initializing heartbeat.
2022-01-20T14:05:58.153Z,1642687558.153 [Onboard ThreadHandler](INFO): Handler Thread ID is 1616
2022-01-20T14:05:58.171Z,1642687558.171 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1617
2022-01-20T14:05:58.190Z,1642687558.190 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1618
2022-01-20T14:05:58.191Z,1642687558.191 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-01-20T14:05:58.194Z,1642687558.194 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1620
2022-01-20T14:05:58.195Z,1642687558.195 [WetLabsSeaOWL_UV_A](INFO): Powering down
2022-01-20T14:05:58.206Z,1642687558.206 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1621
2022-01-20T14:05:58.213Z,1642687558.213 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-01-20T14:05:58.213Z,1642687558.213 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-01-20T14:05:58.213Z,1642687558.213 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-01-20T14:05:58.213Z,1642687558.213 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-01-20T14:05:58.214Z,1642687558.214 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-01-20T14:05:58.215Z,1642687558.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-01-20T14:05:58.215Z,1642687558.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-01-20T14:05:58.215Z,1642687558.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-01-20T14:05:58.215Z,1642687558.215 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-01-20T14:05:58.215Z,1642687558.215 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-01-20T14:05:58.217Z,1642687558.217 [CBIT](DEBUG): Deactivating GF circuits.
2022-01-20T14:05:58.217Z,1642687558.217 [CBIT](DEBUG): Deactivating emergency mode.
2022-01-20T14:05:58.253Z,1642687558.253 [CBIT](DEBUG): Backplane powered.
2022-01-20T14:05:58.253Z,1642687558.253 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-01-20T14:05:58.255Z,1642687558.255 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-01-20T14:05:58.256Z,1642687558.256 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-01-20T14:05:58.256Z,1642687558.256 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-01-20T14:05:58.257Z,1642687558.257 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-01-20T14:05:58.282Z,1642687558.282 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-20T14:05:58.309Z,1642687558.309 [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-01-20T14:05:58.310Z,1642687558.310 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-01-20T14:05:58.386Z,1642687558.386 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-01-20T14:05:58.387Z,1642687558.387 [Default:A.Wait](DEBUG): Construct Wait.
2022-01-20T14:05:58.398Z,1642687558.398 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-01-20T14:05:58.423Z,1642687558.423 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-01-20T14:05:58.454Z,1642687558.454 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-01-20T14:05:58.460Z,1642687558.460 [Default:E.Execute](DEBUG): Construct Execute.
2022-01-20T14:05:58.471Z,1642687558.471 [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-01-20T14:05:58.477Z,1642687558.477 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,Micromodem,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,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,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-20T14:05:58.489Z,1642687558.489 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-01-20T14:05:58.577Z,1642687558.577 [Radio_Surface](INFO): Powering up
2022-01-20T14:05:58.672Z,1642687558.672 [Power24vConverter](INFO): Powering up.
2022-01-20T14:05:58.837Z,1642687558.837 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-01-20T14:05:58.845Z,1642687558.845 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-01-20T14:05:58.846Z,1642687558.846 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-01-20T14:05:58.853Z,1642687558.853 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-01-20T14:05:58.854Z,1642687558.854 [MassServo](DEBUG): Initializing EZServoServo.
2022-01-20T14:05:58.861Z,1642687558.861 [MassServo](DEBUG): Initializing MassServo.
2022-01-20T14:05:58.862Z,1642687558.862 [RudderServo](DEBUG): Initializing EZServoServo.
2022-01-20T14:05:58.869Z,1642687558.869 [RudderServo](DEBUG): Initializing RudderServo.
2022-01-20T14:05:58.870Z,1642687558.870 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-01-20T14:05:58.877Z,1642687558.877 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-01-20T14:05:58.959Z,1642687558.959 [Micromodem](INFO): Powering up
2022-01-20T14:05:58.959Z,1642687558.959 [Micromodem](DEBUG): Initializing Micromodem.
2022-01-20T14:06:03.789Z,1642687563.789 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2022-01-20T14:06:04.188Z,1642687564.188 [Micromodem](INFO): Nmea in: $CATMG,2022-01-20T14:06:05.124655Z,RTC,RTC*56

2022-01-20T14:06:04.188Z,1642687564.188 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2022-01-20T14:06:05.124655Z,RTC,RTC*56

2022-01-20T14:06:04.591Z,1642687564.591 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2022-01-20T14:06:04.591Z,1642687564.591 [Micromodem](INFO): Nmea out: $CCCFG,POW,20*08

2022-01-20T14:06:04.991Z,1642687564.991 [Micromodem](INFO): Nmea in: $CACFG,POW,20*0A

2022-01-20T14:06:04.991Z,1642687564.991 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2022-01-20T14:06:05.395Z,1642687565.395 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2022-01-20T14:06:05.395Z,1642687565.395 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2022-01-20T14:06:05.804Z,1642687565.804 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2022-01-20T14:06:05.804Z,1642687565.804 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2022-01-20T14:06:06.207Z,1642687566.207 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2022-01-20T14:06:06.207Z,1642687566.207 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2022-01-20T14:06:06.607Z,1642687566.607 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2022-01-20T14:06:06.607Z,1642687566.607 [Micromodem](INFO): Nmea out: $CCCFG,FC0,14500*47

2022-01-20T14:06:07.003Z,1642687567.003 [Micromodem](INFO): Nmea in: $CACFG,FC0,14500*45

2022-01-20T14:06:07.003Z,1642687567.003 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2022-01-20T14:06:07.391Z,1642687567.391 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2022-01-20T14:06:07.391Z,1642687567.391 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2022-01-20T14:06:07.789Z,1642687567.789 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2022-01-20T14:06:07.790Z,1642687567.790 [Micromodem](INFO): Nmea out: $CCCLK,2022,01,20,14,06,08*4E

2022-01-20T14:06:08.196Z,1642687568.196 [Micromodem](INFO): Nmea in: $CACLK,2022,1,20,14,6,8*7C

2022-01-20T14:06:08.999Z,1642687568.999 [Micromodem](INFO): Nmea in: $CATMS,0,2022-01-20T14:06:09Z*7D

2022-01-20T14:06:09.001Z,1642687569.001 [Micromodem](INFO): Nmea in: $CATMG,2022-01-20T14:06:09.027000Z,USER_CMD,RTC*1F

2022-01-20T14:06:25.588Z,1642687585.588 [SBIT](IMPORTANT): Beginning Startup BIT
2022-01-20T14:06:25.621Z,1642687585.621 [CBIT](IMPORTANT): Beginning ground fault scan
2022-01-20T14:06:25.964Z,1642687585.964 [NAL9602](INFO): Powering up NAL9602
2022-01-20T14:06:36.664Z,1642687596.664 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.008361
CHAN A1 (24V): -0.023602
CHAN A2 (12V): -0.005472
CHAN A3 (5V): -0.001739
CHAN B0 (3.3V): 0.000584
CHAN B1 (3.15aV): 0.000231
CHAN B2 (3.15bV): 0.000143
CHAN B3 (GND): 0.001736
OPEN: 0.004691
Full Scale: +/- 1 mA
2022-01-20T14:06:36.909Z,1642687596.909 [NAL9602](INFO): NAL9602 initialized
2022-01-20T14:07:19.723Z,1642687639.723 [SBIT](IMPORTANT): SBIT PASSED
2022-01-20T14:07:19.723Z,1642687639.723 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-01-20T14:07:19.725Z,1642687639.725 [SBIT](IMPORTANT): CBIT.gfScanTimeout=2 hour;

2022-01-20T14:07:19.727Z,1642687639.727 [SBIT](IMPORTANT): Express linearApproximation platform_average_current 0.500000 ampere;

2022-01-20T14:07:19.728Z,1642687639.728 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=360.364649 cubic_centimeter;

2022-01-20T14:07:19.728Z,1642687639.728 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.963174 millimeter;

2022-01-20T14:07:20.128Z,1642687640.128 [WorkSite](IMPORTANT): Reading workSite position from Data/workSite.cfg
2022-01-20T14:07:20.132Z,1642687640.132 [WorkSite](IMPORTANT): WorkSite fix at 20220120T140450: (41.524590, -70.671574)
2022-01-20T14:07:20.133Z,1642687640.133 [MissionManager](IMPORTANT): Started mission Startup
2022-01-20T14:07:20.134Z,1642687640.134 [Startup] Running Loop=1
2022-01-20T14:07:20.134Z,1642687640.134 [Startup](DEBUG): Aggregate::initialize Startup
2022-01-20T14:07:20.134Z,1642687640.134 [Startup:A.GoToSurface] Running Loop=1
2022-01-20T14:07:20.134Z,1642687640.134 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-20T14:07:20.135Z,1642687640.135 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-20T14:07:20.135Z,1642687640.135 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-20T14:07:20.135Z,1642687640.135 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-20T14:07:20.136Z,1642687640.136 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-20T14:07:20.136Z,1642687640.136 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-20T14:07:20.138Z,1642687640.138 [Startup:StartupSatComms] Running Loop=1
2022-01-20T14:07:20.138Z,1642687640.138 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-01-20T14:07:20.138Z,1642687640.138 [Startup:StartupSatComms:A] Running Loop=1
2022-01-20T14:07:20.547Z,1642687640.547 [LBLNavigation](INFO): Reset ping filters
2022-01-20T14:07:20.547Z,1642687640.547 [LBLNavigation](INFO): Reset fix filter
2022-01-20T14:07:20.549Z,1642687640.549 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-01-20T14:08:07.434Z,1642687687.434 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-01-20T14:08:13.444Z,1642687693.444 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:08:13.850Z,1642687693.850 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120140813.092488,06,1829,26,0152,0150,186,01,01,01,01,-1,-01,-01,1,3,1,0,150,27.2,0.15,-100,2.85,-01,0.41,54,14500,4000*74

2022-01-20T14:08:20.347Z,1642687700.347 [Startup:StartupSatComms:A](INFO): Timed out from 2022-01-20T14:07:20.1Z
2022-01-20T14:08:20.347Z,1642687700.347 [Startup:StartupSatComms:A] Stopped
2022-01-20T14:08:20.347Z,1642687700.347 [Startup:StartupSatComms:B] Running Loop=1
2022-01-20T14:08:20.776Z,1642687700.776 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-20T14:08:22.723Z,1642687702.723 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004863
2022-01-20T14:08:26.738Z,1642687706.738 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20220120T140546/Courier0000.lzma
2022-01-20T14:08:27.739Z,1642687707.739 [DataOverHttps](INFO): Moved sent file to Logs/20220120T140546/Courier0000.lzma.bak
2022-01-20T14:08:27.739Z,1642687707.739 [DataOverHttps](INFO): SBD MOMSN=1148881
2022-01-20T14:08:36.778Z,1642687716.778 [DataOverHttps](INFO): Sending 871 bytes from file Logs/20220120T140546/Express0001.lzma
2022-01-20T14:08:37.779Z,1642687717.779 [DataOverHttps](INFO): Moved sent file to Logs/20220120T140546/Express0001.lzma.bak
2022-01-20T14:08:37.779Z,1642687717.779 [DataOverHttps](INFO): SBD MOMSN=1148885
2022-01-20T14:08:38.999Z,1642687718.999 [Startup:StartupSatComms:B] Stopped
2022-01-20T14:08:38.000Z,1642687719.000 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-01-20T14:08:38.000Z,1642687719.000 [Startup:StartupSatComms] Stopped
2022-01-20T14:08:38.000Z,1642687719.000 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-01-20T14:08:39.000Z,1642687719.000 [Startup](INFO): Completed Startup
2022-01-20T14:08:39.002Z,1642687719.002 [MissionManager](INFO): Startup is completed.
2022-01-20T14:08:39.002Z,1642687719.002 [MissionManager](INFO): Uninitializing Mission Startup
2022-01-20T14:08:39.002Z,1642687719.002 [Startup] Stopped
2022-01-20T14:08:39.002Z,1642687719.002 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-01-20T14:08:39.002Z,1642687719.002 [Startup:A.GoToSurface] Stopped
2022-01-20T14:08:39.002Z,1642687719.002 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-20T14:08:39.304Z,1642687719.304 [MissionManager](IMPORTANT): Started mission Default
2022-01-20T14:08:39.304Z,1642687719.304 [Default] Running Loop=1
2022-01-20T14:08:39.304Z,1642687719.304 [Default](DEBUG): Aggregate::initialize Default
2022-01-20T14:08:39.304Z,1642687719.304 [Default:B.GoToSurface] Running Loop=1
2022-01-20T14:08:39.304Z,1642687719.304 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-01-20T14:08:39.305Z,1642687719.305 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-01-20T14:08:39.305Z,1642687719.305 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-01-20T14:08:39.305Z,1642687719.305 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-01-20T14:08:39.306Z,1642687719.306 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-01-20T14:08:39.306Z,1642687719.306 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-01-20T14:08:39.306Z,1642687719.306 [Default:A.Wait] Running Loop=1
2022-01-20T14:08:39.306Z,1642687719.306 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-01-20T14:08:52.658Z,1642687732.658 [Default:A.Wait](INFO): Done Waiting.
2022-01-20T14:08:52.658Z,1642687732.658 [Default:A.Wait] Stopped
2022-01-20T14:08:52.659Z,1642687732.659 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-01-20T14:08:53.076Z,1642687733.076 [Default:CheckIn] Running Loop=1
2022-01-20T14:08:53.077Z,1642687733.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-20T14:08:53.077Z,1642687733.077 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-20T14:08:53.443Z,1642687733.443 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-01-20T14:09:03.538Z,1642687743.538 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:09:03.544Z,1642687743.544 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120140903.102515,06,649,22,0093,0150,194,01,01,01,01,-1,-01,-01,1,3,7,0,150,25.2,0.03,-100,2.97,-01,-0.10,56,14500,4000*60

2022-01-20T14:09:20.736Z,1642687760.736 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 150 cubic_centimeter
2022-01-20T14:09:20.741Z,1642687760.741 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2022-01-20T14:09:21.045Z,1642687761.045 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,Micromodem,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,WorkSite,LBLNavigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-20T14:09:23.011Z,1642687763.011 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2022-01-20T14:09:31.053Z,1642687771.053 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2022-01-20T14:09:32.737Z,1642687772.737 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position
2022-01-20T14:09:33.131Z,1642687773.131 [Reporter](INFO): platform_buoyancy_position 880.846436 cc
2022-01-20T14:09:33.499Z,1642687773.499 [Reporter](INFO): platform_buoyancy_position 876.548828 cc
2022-01-20T14:09:33.908Z,1642687773.908 [Reporter](INFO): platform_buoyancy_position 872.116943 cc
2022-01-20T14:09:34.337Z,1642687774.337 [Reporter](INFO): platform_buoyancy_position 865.804932 cc
2022-01-20T14:09:34.703Z,1642687774.703 [Reporter](INFO): platform_buoyancy_position 861.372925 cc
2022-01-20T14:09:35.155Z,1642687775.155 [Reporter](INFO): platform_buoyancy_position 854.926636 cc
2022-01-20T14:09:35.503Z,1642687775.503 [Reporter](INFO): platform_buoyancy_position 850.629028 cc
2022-01-20T14:09:35.918Z,1642687775.918 [Reporter](INFO): platform_buoyancy_position 844.316895 cc
2022-01-20T14:09:36.335Z,1642687776.335 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2022-01-20T14:09:36.732Z,1642687776.732 [Reporter](INFO): platform_buoyancy_position 833.572876 cc
2022-01-20T14:09:37.155Z,1642687777.155 [Reporter](INFO): platform_buoyancy_position 829.140991 cc
2022-01-20T14:09:37.635Z,1642687777.635 [Reporter](INFO): platform_buoyancy_position 822.694580 cc
2022-01-20T14:09:37.976Z,1642687777.976 [Reporter](INFO): platform_buoyancy_position 818.396973 cc
2022-01-20T14:09:38.394Z,1642687778.394 [Reporter](INFO): platform_buoyancy_position 812.084900 cc
2022-01-20T14:09:38.780Z,1642687778.780 [Reporter](INFO): platform_buoyancy_position 807.787292 cc
2022-01-20T14:09:39.196Z,1642687779.196 [Reporter](INFO): platform_buoyancy_position 801.206543 cc
2022-01-20T14:09:39.567Z,1642687779.567 [Reporter](INFO): platform_buoyancy_position 796.908997 cc
2022-01-20T14:09:39.995Z,1642687779.995 [Reporter](INFO): platform_buoyancy_position 790.596863 cc
2022-01-20T14:09:40.380Z,1642687780.380 [Reporter](INFO): platform_buoyancy_position 786.299255 cc
2022-01-20T14:09:40.770Z,1642687780.770 [Reporter](INFO): platform_buoyancy_position 781.867371 cc
2022-01-20T14:09:41.219Z,1642687781.219 [Reporter](INFO): platform_buoyancy_position 775.420959 cc
2022-01-20T14:09:41.563Z,1642687781.563 [Reporter](INFO): platform_buoyancy_position 771.123352 cc
2022-01-20T14:09:42.013Z,1642687782.013 [Reporter](INFO): platform_buoyancy_position 764.811279 cc
2022-01-20T14:09:42.391Z,1642687782.391 [Reporter](INFO): platform_buoyancy_position 760.379395 cc
2022-01-20T14:09:42.807Z,1642687782.807 [Reporter](INFO): platform_buoyancy_position 754.067261 cc
2022-01-20T14:09:43.235Z,1642687783.235 [Reporter](INFO): platform_buoyancy_position 749.769653 cc
2022-01-20T14:09:43.603Z,1642687783.603 [Reporter](INFO): platform_buoyancy_position 743.323242 cc
2022-01-20T14:09:44.017Z,1642687784.017 [Reporter](INFO): platform_buoyancy_position 739.025696 cc
2022-01-20T14:09:44.420Z,1642687784.420 [Reporter](INFO): platform_buoyancy_position 732.579285 cc
2022-01-20T14:09:44.795Z,1642687784.795 [Reporter](INFO): platform_buoyancy_position 728.281677 cc
2022-01-20T14:09:45.311Z,1642687785.311 [Reporter](INFO): platform_buoyancy_position 721.700989 cc
2022-01-20T14:09:45.624Z,1642687785.624 [Reporter](INFO): platform_buoyancy_position 717.403381 cc
2022-01-20T14:09:46.037Z,1642687786.037 [Reporter](INFO): platform_buoyancy_position 712.434265 cc
2022-01-20T14:09:46.440Z,1642687786.440 [Reporter](INFO): platform_buoyancy_position 706.659363 cc
2022-01-20T14:09:46.848Z,1642687786.848 [Reporter](INFO): platform_buoyancy_position 701.958862 cc
2022-01-20T14:09:47.252Z,1642687787.252 [Reporter](INFO): platform_buoyancy_position 695.915344 cc
2022-01-20T14:09:47.668Z,1642687787.668 [Reporter](INFO): platform_buoyancy_position 690.811951 cc
2022-01-20T14:09:48.088Z,1642687788.088 [Reporter](INFO): platform_buoyancy_position 685.305664 cc
2022-01-20T14:09:48.452Z,1642687788.452 [Reporter](INFO): platform_buoyancy_position 680.739441 cc
2022-01-20T14:09:48.881Z,1642687788.881 [Reporter](INFO): platform_buoyancy_position 674.561646 cc
2022-01-20T14:09:49.291Z,1642687789.291 [Reporter](INFO): platform_buoyancy_position 669.726868 cc
2022-01-20T14:09:49.658Z,1642687789.658 [Reporter](INFO): platform_buoyancy_position 663.817688 cc
2022-01-20T14:09:50.073Z,1642687790.073 [Reporter](INFO): platform_buoyancy_position 659.520081 cc
2022-01-20T14:09:50.469Z,1642687790.469 [Reporter](INFO): platform_buoyancy_position 653.073669 cc
2022-01-20T14:09:51.031Z,1642687791.031 [Reporter](INFO): platform_buoyancy_position 646.627258 cc
2022-01-20T14:09:51.284Z,1642687791.284 [Reporter](INFO): platform_buoyancy_position 642.329651 cc
2022-01-20T14:09:51.687Z,1642687791.687 [Reporter](INFO): platform_buoyancy_position 637.897766 cc
2022-01-20T14:09:52.118Z,1642687792.118 [Reporter](INFO): platform_buoyancy_position 631.854248 cc
2022-01-20T14:09:52.479Z,1642687792.479 [Reporter](INFO): platform_buoyancy_position 627.153748 cc
2022-01-20T14:09:52.908Z,1642687792.908 [Reporter](INFO): platform_buoyancy_position 622.990479 cc
2022-01-20T14:09:53.331Z,1642687793.331 [Reporter](INFO): platform_buoyancy_position 616.544067 cc
2022-01-20T14:09:53.717Z,1642687793.717 [Reporter](INFO): platform_buoyancy_position 611.440674 cc
2022-01-20T14:09:54.096Z,1642687794.096 [Reporter](INFO): platform_buoyancy_position 605.800049 cc
2022-01-20T14:09:54.512Z,1642687794.512 [Reporter](INFO): platform_buoyancy_position 601.233887 cc
2022-01-20T14:09:54.951Z,1642687794.951 [Reporter](INFO): platform_buoyancy_position 595.056030 cc
2022-01-20T14:09:55.331Z,1642687795.331 [Reporter](INFO): platform_buoyancy_position 590.758484 cc
2022-01-20T14:09:55.726Z,1642687795.726 [Reporter](INFO): platform_buoyancy_position 584.177734 cc
2022-01-20T14:09:56.127Z,1642687796.127 [Reporter](INFO): platform_buoyancy_position 579.880127 cc
2022-01-20T14:09:56.504Z,1642687796.504 [Reporter](INFO): platform_buoyancy_position 573.568054 cc
2022-01-20T14:09:57.024Z,1642687797.024 [Reporter](INFO): platform_buoyancy_position 567.121643 cc
2022-01-20T14:09:57.342Z,1642687797.342 [Reporter](INFO): platform_buoyancy_position 564.301331 cc
2022-01-20T14:09:57.719Z,1642687797.719 [Reporter](INFO): platform_buoyancy_position 558.392151 cc
2022-01-20T14:09:58.135Z,1642687798.135 [Reporter](INFO): platform_buoyancy_position 554.094543 cc
2022-01-20T14:09:58.623Z,1642687798.623 [Reporter](INFO): platform_buoyancy_position 546.439453 cc
2022-01-20T14:09:59.079Z,1642687799.079 [Reporter](INFO): platform_buoyancy_position 541.201782 cc
2022-01-20T14:09:59.375Z,1642687799.375 [Reporter](INFO): platform_buoyancy_position 536.904175 cc
2022-01-20T14:09:59.798Z,1642687799.798 [Reporter](INFO): platform_buoyancy_position 530.592041 cc
2022-01-20T14:10:00.163Z,1642687800.163 [Reporter](INFO): platform_buoyancy_position 526.294434 cc
2022-01-20T14:10:00.568Z,1642687800.568 [Reporter](INFO): platform_buoyancy_position 521.862549 cc
2022-01-20T14:10:01.036Z,1642687801.036 [Reporter](INFO): platform_buoyancy_position 515.416138 cc
2022-01-20T14:10:01.352Z,1642687801.352 [Reporter](INFO): platform_buoyancy_position 511.118500 cc
2022-01-20T14:10:01.772Z,1642687801.772 [Reporter](INFO): platform_buoyancy_position 504.940735 cc
2022-01-20T14:10:02.192Z,1642687802.192 [Reporter](INFO): platform_buoyancy_position 500.508881 cc
2022-01-20T14:10:02.588Z,1642687802.588 [Reporter](INFO): platform_buoyancy_position 494.331055 cc
2022-01-20T14:10:03.016Z,1642687803.016 [Reporter](INFO): platform_buoyancy_position 489.630554 cc
2022-01-20T14:10:03.400Z,1642687803.400 [Reporter](INFO): platform_buoyancy_position 485.198669 cc
2022-01-20T14:10:03.803Z,1642687803.803 [Reporter](INFO): platform_buoyancy_position 479.020874 cc
2022-01-20T14:10:04.277Z,1642687804.277 [Reporter](INFO): platform_buoyancy_position 472.708740 cc
2022-01-20T14:10:04.640Z,1642687804.640 [Reporter](INFO): platform_buoyancy_position 468.276855 cc
2022-01-20T14:10:05.062Z,1642687805.062 [Reporter](INFO): platform_buoyancy_position 462.367676 cc
2022-01-20T14:10:05.423Z,1642687805.423 [Reporter](INFO): platform_buoyancy_position 457.667175 cc
2022-01-20T14:10:05.831Z,1642687805.831 [Reporter](INFO): platform_buoyancy_position 452.698059 cc
2022-01-20T14:10:06.215Z,1642687806.215 [Reporter](INFO): platform_buoyancy_position 446.654541 cc
2022-01-20T14:10:06.619Z,1642687806.619 [Reporter](INFO): platform_buoyancy_position 442.356934 cc
2022-01-20T14:10:07.083Z,1642687807.083 [Reporter](INFO): platform_buoyancy_position 436.044861 cc
2022-01-20T14:10:07.419Z,1642687807.419 [Reporter](INFO): platform_buoyancy_position 431.612976 cc
2022-01-20T14:10:07.835Z,1642687807.835 [Reporter](INFO): platform_buoyancy_position 425.300842 cc
2022-01-20T14:10:08.247Z,1642687808.247 [Reporter](INFO): platform_buoyancy_position 420.600342 cc
2022-01-20T14:10:08.648Z,1642687808.648 [Reporter](INFO): platform_buoyancy_position 414.556824 cc
2022-01-20T14:10:09.084Z,1642687809.084 [Reporter](INFO): platform_buoyancy_position 409.856323 cc
2022-01-20T14:10:09.463Z,1642687809.463 [Reporter](INFO): platform_buoyancy_position 403.678528 cc
2022-01-20T14:10:09.864Z,1642687809.864 [Reporter](INFO): platform_buoyancy_position 399.515259 cc
2022-01-20T14:10:10.280Z,1642687810.280 [Reporter](INFO): platform_buoyancy_position 393.203125 cc
2022-01-20T14:10:10.644Z,1642687810.644 [Reporter](INFO): platform_buoyancy_position 388.771240 cc
2022-01-20T14:10:11.123Z,1642687811.123 [Reporter](INFO): platform_buoyancy_position 382.190552 cc
2022-01-20T14:10:11.480Z,1642687811.480 [Reporter](INFO): platform_buoyancy_position 377.892944 cc
2022-01-20T14:10:11.931Z,1642687811.931 [Reporter](INFO): platform_buoyancy_position 371.715149 cc
2022-01-20T14:10:12.280Z,1642687812.280 [Reporter](INFO): platform_buoyancy_position 367.283264 cc
2022-01-20T14:10:12.680Z,1642687812.681 [Reporter](INFO): platform_buoyancy_position 362.582764 cc
2022-01-20T14:10:13.127Z,1642687813.127 [Reporter](INFO): platform_buoyancy_position 356.539246 cc
2022-01-20T14:10:13.525Z,1642687813.525 [Reporter](INFO): platform_buoyancy_position 352.241638 cc
2022-01-20T14:10:13.895Z,1642687813.895 [Reporter](INFO): platform_buoyancy_position 347.944031 cc
2022-01-20T14:10:14.310Z,1642687814.310 [Reporter](INFO): platform_buoyancy_position 341.363342 cc
2022-01-20T14:10:14.730Z,1642687814.730 [Reporter](INFO): platform_buoyancy_position 335.185547 cc
2022-01-20T14:10:15.144Z,1642687815.144 [Reporter](INFO): platform_buoyancy_position 330.753662 cc
2022-01-20T14:10:15.519Z,1642687815.519 [Reporter](INFO): platform_buoyancy_position 324.844421 cc
2022-01-20T14:10:15.932Z,1642687815.932 [Reporter](INFO): platform_buoyancy_position 320.009644 cc
2022-01-20T14:10:16.342Z,1642687816.342 [Reporter](INFO): platform_buoyancy_position 315.577759 cc
2022-01-20T14:10:16.711Z,1642687816.711 [Reporter](INFO): platform_buoyancy_position 309.399933 cc
2022-01-20T14:10:17.172Z,1642687817.172 [Reporter](INFO): platform_buoyancy_position 302.953522 cc
2022-01-20T14:10:17.547Z,1642687817.547 [Reporter](INFO): platform_buoyancy_position 298.387329 cc
2022-01-20T14:10:18.047Z,1642687818.047 [Reporter](INFO): platform_buoyancy_position 292.075226 cc
2022-01-20T14:10:18.335Z,1642687818.335 [Reporter](INFO): platform_buoyancy_position 287.777649 cc
2022-01-20T14:10:18.744Z,1642687818.744 [Reporter](INFO): platform_buoyancy_position 283.345734 cc
2022-01-20T14:10:19.199Z,1642687819.199 [Reporter](INFO): platform_buoyancy_position 276.899323 cc
2022-01-20T14:10:19.575Z,1642687819.575 [Reporter](INFO): platform_buoyancy_position 272.601746 cc
2022-01-20T14:10:19.991Z,1642687819.991 [Reporter](INFO): platform_buoyancy_position 266.289642 cc
2022-01-20T14:10:20.371Z,1642687820.371 [Reporter](INFO): platform_buoyancy_position 261.857727 cc
2022-01-20T14:10:20.775Z,1642687820.775 [Reporter](INFO): platform_buoyancy_position 255.545624 cc
2022-01-20T14:10:21.236Z,1642687821.236 [Reporter](INFO): platform_buoyancy_position 250.979446 cc
2022-01-20T14:10:21.563Z,1642687821.563 [Reporter](INFO): platform_buoyancy_position 245.473145 cc
2022-01-20T14:10:21.971Z,1642687821.971 [Reporter](INFO): platform_buoyancy_position 240.369720 cc
2022-01-20T14:10:22.392Z,1642687822.392 [Reporter](INFO): platform_buoyancy_position 235.803528 cc
2022-01-20T14:10:22.800Z,1642687822.800 [Reporter](INFO): platform_buoyancy_position 229.760040 cc
2022-01-20T14:10:23.215Z,1642687823.215 [Reporter](INFO): platform_buoyancy_position 223.313629 cc
2022-01-20T14:10:23.631Z,1642687823.631 [Reporter](INFO): platform_buoyancy_position 219.016022 cc
2022-01-20T14:10:24.047Z,1642687824.047 [Reporter](INFO): platform_buoyancy_position 213.509735 cc
2022-01-20T14:10:24.410Z,1642687824.410 [Reporter](INFO): platform_buoyancy_position 208.406342 cc
2022-01-20T14:10:24.827Z,1642687824.827 [Reporter](INFO): platform_buoyancy_position 203.974426 cc
2022-01-20T14:10:25.315Z,1642687825.315 [Reporter](INFO): platform_buoyancy_position 197.528015 cc
2022-01-20T14:10:25.647Z,1642687825.647 [Reporter](INFO): platform_buoyancy_position 193.096130 cc
2022-01-20T14:10:26.081Z,1642687826.081 [Reporter](INFO): platform_buoyancy_position 186.784027 cc
2022-01-20T14:10:26.423Z,1642687826.423 [Reporter](INFO): platform_buoyancy_position 182.486420 cc
2022-01-20T14:10:26.832Z,1642687826.832 [Reporter](INFO): platform_buoyancy_position 176.040039 cc
2022-01-20T14:10:27.255Z,1642687827.255 [Reporter](INFO): platform_buoyancy_position 171.608124 cc
2022-01-20T14:10:27.616Z,1642687827.616 [Reporter](INFO): platform_buoyancy_position 167.310516 cc
2022-01-20T14:10:28.035Z,1642687828.035 [Reporter](INFO): platform_buoyancy_position 160.864136 cc
2022-01-20T14:10:28.479Z,1642687828.479 [Reporter](INFO): platform_buoyancy_position 154.552032 cc
2022-01-20T14:10:34.799Z,1642687834.799 [CommandExec](IMPORTANT): got command maintain clear
2022-01-20T14:10:34.979Z,1642687834.979 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,Micromodem,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,RDI_PathfinderUp,PAR_Licor,Depth_Keller,PAR_Licor,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,LcmUniversalReporter,Reporter,LogSplitter,
2022-01-20T14:10:37.364Z,1642687837.364 [Reporter](INFO): platform_buoyancy_position 152.268921 cc
2022-01-20T14:10:38.146Z,1642687838.146 [Reporter](INFO): platform_buoyancy_position 152.134613 cc
2022-01-20T14:10:38.591Z,1642687838.591 [Reporter](INFO): platform_buoyancy_position 154.417725 cc
2022-01-20T14:10:39.016Z,1642687839.016 [Reporter](INFO): platform_buoyancy_position 160.998413 cc
2022-01-20T14:10:39.395Z,1642687839.395 [Reporter](INFO): platform_buoyancy_position 167.444824 cc
2022-01-20T14:10:39.762Z,1642687839.762 [Reporter](INFO): platform_buoyancy_position 171.876740 cc
2022-01-20T14:10:40.167Z,1642687840.167 [Reporter](INFO): platform_buoyancy_position 178.054535 cc
2022-01-20T14:10:40.588Z,1642687840.588 [Reporter](INFO): platform_buoyancy_position 182.486420 cc
2022-01-20T14:10:41.003Z,1642687841.003 [Reporter](INFO): platform_buoyancy_position 190.678741 cc
2022-01-20T14:10:41.383Z,1642687841.383 [Reporter](INFO): platform_buoyancy_position 195.244934 cc
2022-01-20T14:10:41.803Z,1642687841.803 [Reporter](INFO): platform_buoyancy_position 199.676819 cc
2022-01-20T14:10:42.199Z,1642687842.199 [Reporter](INFO): platform_buoyancy_position 204.108734 cc
2022-01-20T14:10:42.572Z,1642687842.572 [Reporter](INFO): platform_buoyancy_position 210.420837 cc
2022-01-20T14:10:43.047Z,1642687843.047 [Reporter](INFO): platform_buoyancy_position 216.867218 cc
2022-01-20T14:10:43.423Z,1642687843.423 [Reporter](INFO): platform_buoyancy_position 221.030518 cc
2022-01-20T14:10:43.813Z,1642687843.813 [Reporter](INFO): platform_buoyancy_position 225.328125 cc
2022-01-20T14:10:44.225Z,1642687844.225 [Reporter](INFO): platform_buoyancy_position 231.774536 cc
2022-01-20T14:10:44.607Z,1642687844.607 [Reporter](INFO): platform_buoyancy_position 236.206421 cc
2022-01-20T14:10:45.130Z,1642687845.130 [Reporter](INFO): platform_buoyancy_position 244.667313 cc
2022-01-20T14:10:45.428Z,1642687845.428 [Reporter](INFO): platform_buoyancy_position 247.084732 cc
2022-01-20T14:10:45.859Z,1642687845.859 [Reporter](INFO): platform_buoyancy_position 253.396805 cc
2022-01-20T14:10:46.278Z,1642687846.278 [Reporter](INFO): platform_buoyancy_position 257.560120 cc
2022-01-20T14:10:46.639Z,1642687846.639 [Reporter](INFO): platform_buoyancy_position 264.006531 cc
2022-01-20T14:10:47.110Z,1642687847.110 [Reporter](INFO): platform_buoyancy_position 270.452942 cc
2022-01-20T14:10:47.423Z,1642687847.423 [Reporter](INFO): platform_buoyancy_position 274.884827 cc
2022-01-20T14:10:47.840Z,1642687847.840 [Reporter](INFO): platform_buoyancy_position 279.182434 cc
2022-01-20T14:10:48.256Z,1642687848.256 [Reporter](INFO): platform_buoyancy_position 285.494537 cc
2022-01-20T14:10:48.659Z,1642687848.659 [Reporter](INFO): platform_buoyancy_position 289.926422 cc
2022-01-20T14:10:49.071Z,1642687849.071 [Reporter](INFO): platform_buoyancy_position 296.238525 cc
2022-01-20T14:10:49.466Z,1642687849.466 [Reporter](INFO): platform_buoyancy_position 300.670441 cc
2022-01-20T14:10:49.876Z,1642687849.876 [Reporter](INFO): platform_buoyancy_position 306.848236 cc
2022-01-20T14:10:50.244Z,1642687850.244 [Reporter](INFO): platform_buoyancy_position 311.280151 cc
2022-01-20T14:10:50.667Z,1642687850.667 [Reporter](INFO): platform_buoyancy_position 316.920715 cc
2022-01-20T14:10:51.123Z,1642687851.123 [Reporter](INFO): platform_buoyancy_position 322.024109 cc
2022-01-20T14:10:51.496Z,1642687851.496 [Reporter](INFO): platform_buoyancy_position 326.858948 cc
2022-01-20T14:10:51.974Z,1642687851.974 [Reporter](INFO): platform_buoyancy_position 332.902466 cc
2022-01-20T14:10:52.324Z,1642687852.324 [Reporter](INFO): platform_buoyancy_position 337.200012 cc
2022-01-20T14:10:52.706Z,1642687852.706 [Reporter](INFO): platform_buoyancy_position 343.512146 cc
2022-01-20T14:10:53.127Z,1642687853.127 [Reporter](INFO): platform_buoyancy_position 347.944031 cc
2022-01-20T14:10:53.511Z,1642687853.511 [Reporter](INFO): platform_buoyancy_position 352.241638 cc
2022-01-20T14:10:53.914Z,1642687853.914 [Reporter](INFO): platform_buoyancy_position 358.553711 cc
2022-01-20T14:10:54.295Z,1642687854.295 [Reporter](INFO): platform_buoyancy_position 362.851318 cc
2022-01-20T14:10:54.704Z,1642687854.704 [Reporter](INFO): platform_buoyancy_position 369.297729 cc
2022-01-20T14:10:55.157Z,1642687855.157 [Reporter](INFO): platform_buoyancy_position 375.744141 cc
2022-01-20T14:10:55.499Z,1642687855.499 [Reporter](INFO): platform_buoyancy_position 380.176025 cc
2022-01-20T14:10:55.908Z,1642687855.908 [Reporter](INFO): platform_buoyancy_position 384.473633 cc
2022-01-20T14:10:56.328Z,1642687856.328 [Reporter](INFO): platform_buoyancy_position 390.785767 cc
2022-01-20T14:10:56.736Z,1642687856.736 [Reporter](INFO): platform_buoyancy_position 395.351929 cc
2022-01-20T14:10:57.172Z,1642687857.172 [Reporter](INFO): platform_buoyancy_position 401.664062 cc
2022-01-20T14:10:57.572Z,1642687857.572 [Reporter](INFO): platform_buoyancy_position 405.827332 cc
2022-01-20T14:10:57.936Z,1642687857.936 [Reporter](INFO): platform_buoyancy_position 410.259216 cc
2022-01-20T14:10:58.441Z,1642687858.441 [Reporter](INFO): platform_buoyancy_position 416.705627 cc
2022-01-20T14:10:58.772Z,1642687858.772 [Reporter](INFO): platform_buoyancy_position 423.017761 cc
2022-01-20T14:10:59.195Z,1642687859.195 [Reporter](INFO): platform_buoyancy_position 427.583923 cc
2022-01-20T14:10:59.576Z,1642687859.576 [Reporter](INFO): platform_buoyancy_position 431.881531 cc
2022-01-20T14:10:59.975Z,1642687859.975 [Reporter](INFO): platform_buoyancy_position 438.193665 cc
2022-01-20T14:11:00.367Z,1642687860.367 [Reporter](INFO): platform_buoyancy_position 442.491272 cc
2022-01-20T14:11:00.775Z,1642687860.775 [Reporter](INFO): platform_buoyancy_position 448.937622 cc
2022-01-20T14:11:01.207Z,1642687861.207 [Reporter](INFO): platform_buoyancy_position 455.115479 cc
2022-01-20T14:11:01.578Z,1642687861.578 [Reporter](INFO): platform_buoyancy_position 459.413025 cc
2022-01-20T14:11:02.015Z,1642687862.015 [Reporter](INFO): platform_buoyancy_position 464.113525 cc
2022-01-20T14:11:02.411Z,1642687862.411 [Reporter](INFO): platform_buoyancy_position 470.425659 cc
2022-01-20T14:11:02.807Z,1642687862.807 [Reporter](INFO): platform_buoyancy_position 474.857544 cc
2022-01-20T14:11:03.231Z,1642687863.231 [Reporter](INFO): platform_buoyancy_position 481.035339 cc
2022-01-20T14:11:03.600Z,1642687863.600 [Reporter](INFO): platform_buoyancy_position 485.332947 cc
2022-01-20T14:11:04.020Z,1642687864.020 [Reporter](INFO): platform_buoyancy_position 491.779358 cc
2022-01-20T14:11:04.384Z,1642687864.384 [Reporter](INFO): platform_buoyancy_position 496.076935 cc
2022-01-20T14:11:04.808Z,1642687864.808 [Reporter](INFO): platform_buoyancy_position 500.508881 cc
2022-01-20T14:11:05.235Z,1642687865.235 [Reporter](INFO): platform_buoyancy_position 506.820923 cc
2022-01-20T14:11:05.663Z,1642687865.663 [Reporter](INFO): platform_buoyancy_position 513.267334 cc
2022-01-20T14:11:06.022Z,1642687866.022 [Reporter](INFO): platform_buoyancy_position 517.699280 cc
2022-01-20T14:11:06.446Z,1642687866.446 [Reporter](INFO): platform_buoyancy_position 522.936951 cc
2022-01-20T14:11:06.874Z,1642687866.874 [Reporter](INFO): platform_buoyancy_position 528.443237 cc
2022-01-20T14:11:07.267Z,1642687867.267 [Reporter](INFO): platform_buoyancy_position 534.755371 cc
2022-01-20T14:11:07.651Z,1642687867.651 [Reporter](INFO): platform_buoyancy_position 539.187256 cc
2022-01-20T14:11:08.060Z,1642687868.060 [Reporter](INFO): platform_buoyancy_position 544.290649 cc
2022-01-20T14:11:08.452Z,1642687868.452 [Reporter](INFO): platform_buoyancy_position 549.931274 cc
2022-01-20T14:11:08.868Z,1642687868.868 [Reporter](INFO): platform_buoyancy_position 556.243347 cc
2022-01-20T14:11:09.267Z,1642687869.267 [Reporter](INFO): platform_buoyancy_position 560.675232 cc
2022-01-20T14:11:09.643Z,1642687869.643 [Reporter](INFO): platform_buoyancy_position 564.838562 cc
2022-01-20T14:11:10.055Z,1642687870.055 [Reporter](INFO): platform_buoyancy_position 571.150635 cc
2022-01-20T14:11:10.471Z,1642687870.471 [Reporter](INFO): platform_buoyancy_position 575.716858 cc
2022-01-20T14:11:10.861Z,1642687870.861 [Reporter](INFO): platform_buoyancy_position 580.283081 cc
2022-01-20T14:11:11.311Z,1642687871.311 [Reporter](INFO): platform_buoyancy_position 586.460876 cc
2022-01-20T14:11:11.739Z,1642687871.739 [Reporter](INFO): platform_buoyancy_position 592.772949 cc
2022-01-20T14:11:12.108Z,1642687872.108 [Reporter](INFO): platform_buoyancy_position 597.070557 cc
2022-01-20T14:11:12.504Z,1642687872.504 [Reporter](INFO): platform_buoyancy_position 601.368164 cc
2022-01-20T14:11:12.972Z,1642687872.972 [Reporter](INFO): platform_buoyancy_position 608.083130 cc
2022-01-20T14:11:13.327Z,1642687873.327 [Reporter](INFO): platform_buoyancy_position 613.992371 cc
2022-01-20T14:11:13.707Z,1642687873.707 [Reporter](INFO): platform_buoyancy_position 618.558533 cc
2022-01-20T14:11:14.116Z,1642687874.116 [Reporter](INFO): platform_buoyancy_position 623.259033 cc
2022-01-20T14:11:14.487Z,1642687874.487 [Reporter](INFO): platform_buoyancy_position 627.288086 cc
2022-01-20T14:11:14.948Z,1642687874.948 [Reporter](INFO): platform_buoyancy_position 633.868774 cc
2022-01-20T14:11:15.331Z,1642687875.331 [Reporter](INFO): platform_buoyancy_position 638.972168 cc
2022-01-20T14:11:15.699Z,1642687875.699 [Reporter](INFO): platform_buoyancy_position 644.478455 cc
2022-01-20T14:11:16.115Z,1642687876.115 [Reporter](INFO): platform_buoyancy_position 648.776062 cc
2022-01-20T14:11:16.535Z,1642687876.535 [Reporter](INFO): platform_buoyancy_position 655.088135 cc
2022-01-20T14:11:17.007Z,1642687877.007 [Reporter](INFO): platform_buoyancy_position 661.534546 cc
2022-01-20T14:11:17.363Z,1642687877.363 [Reporter](INFO): platform_buoyancy_position 665.832153 cc
2022-01-20T14:11:17.743Z,1642687877.743 [Reporter](INFO): platform_buoyancy_position 670.129761 cc
2022-01-20T14:11:18.155Z,1642687878.155 [Reporter](INFO): platform_buoyancy_position 676.576172 cc
2022-01-20T14:11:18.549Z,1642687878.549 [Reporter](INFO): platform_buoyancy_position 681.008057 cc
2022-01-20T14:11:19.099Z,1642687879.099 [Reporter](INFO): platform_buoyancy_position 689.468994 cc
2022-01-20T14:11:19.367Z,1642687879.367 [Reporter](INFO): platform_buoyancy_position 691.752075 cc
2022-01-20T14:11:19.767Z,1642687879.767 [Reporter](INFO): platform_buoyancy_position 698.198486 cc
2022-01-20T14:11:20.188Z,1642687880.188 [Reporter](INFO): platform_buoyancy_position 702.496094 cc
2022-01-20T14:11:20.548Z,1642687880.548 [Reporter](INFO): platform_buoyancy_position 708.942444 cc
2022-01-20T14:11:21.031Z,1642687881.031 [Reporter](INFO): platform_buoyancy_position 714.045837 cc
2022-01-20T14:11:21.385Z,1642687881.385 [Reporter](INFO): platform_buoyancy_position 719.686462 cc
2022-01-20T14:11:21.763Z,1642687881.763 [Reporter](INFO): platform_buoyancy_position 723.984070 cc
2022-01-20T14:11:22.122Z,1642687882.122 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:11:22.122Z,1642687882.122 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:11:22.283Z,1642687882.283 [Reporter](INFO): platform_buoyancy_position 730.430481 cc
2022-01-20T14:11:22.525Z,1642687882.525 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:11:22.530Z,1642687882.530 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141121.092170,06,853,23,0093,0150,246,02,02,01,02,-1,-01,-01,1,3,4,0,150,78.2,-0.41,-100,3.41,-01,-3.87,-12,14500,4000*67

2022-01-20T14:11:22.607Z,1642687882.607 [Reporter](INFO): platform_buoyancy_position 734.728088 cc
2022-01-20T14:11:23.038Z,1642687883.038 [Reporter](INFO): platform_buoyancy_position 741.174500 cc
2022-01-20T14:11:23.449Z,1642687883.449 [Reporter](INFO): platform_buoyancy_position 745.606384 cc
2022-01-20T14:11:23.798Z,1642687883.798 [Reporter](INFO): platform_buoyancy_position 749.769653 cc
2022-01-20T14:11:24.251Z,1642687884.251 [Reporter](INFO): platform_buoyancy_position 756.081787 cc
2022-01-20T14:11:24.615Z,1642687884.615 [Reporter](INFO): platform_buoyancy_position 760.916565 cc
2022-01-20T14:11:25.102Z,1642687885.102 [Reporter](INFO): platform_buoyancy_position 766.825745 cc
2022-01-20T14:11:25.418Z,1642687885.418 [Reporter](INFO): platform_buoyancy_position 771.257690 cc
2022-01-20T14:11:25.824Z,1642687885.824 [Reporter](INFO): platform_buoyancy_position 777.435486 cc
2022-01-20T14:11:26.240Z,1642687886.240 [Reporter](INFO): platform_buoyancy_position 782.001648 cc
2022-01-20T14:11:26.660Z,1642687886.660 [Reporter](INFO): platform_buoyancy_position 788.448059 cc
2022-01-20T14:11:27.080Z,1642687887.080 [Reporter](INFO): platform_buoyancy_position 792.745667 cc
2022-01-20T14:11:27.462Z,1642687887.462 [Reporter](INFO): platform_buoyancy_position 799.192078 cc
2022-01-20T14:11:27.869Z,1642687887.869 [Reporter](INFO): platform_buoyancy_position 803.489685 cc
2022-01-20T14:11:28.267Z,1642687888.267 [Reporter](INFO): platform_buoyancy_position 809.936096 cc
2022-01-20T14:11:28.651Z,1642687888.651 [Reporter](INFO): platform_buoyancy_position 814.233704 cc
2022-01-20T14:11:29.071Z,1642687889.071 [Reporter](INFO): platform_buoyancy_position 820.277161 cc
2022-01-20T14:11:29.444Z,1642687889.444 [Reporter](INFO): platform_buoyancy_position 824.843384 cc
2022-01-20T14:11:29.870Z,1642687889.870 [Reporter](INFO): platform_buoyancy_position 829.140991 cc
2022-01-20T14:11:30.279Z,1642687890.279 [Reporter](INFO): platform_buoyancy_position 835.587402 cc
2022-01-20T14:11:30.648Z,1642687890.648 [Reporter](INFO): platform_buoyancy_position 839.885010 cc
2022-01-20T14:11:31.112Z,1642687891.112 [Reporter](INFO): platform_buoyancy_position 846.465698 cc
2022-01-20T14:11:31.482Z,1642687891.482 [Reporter](INFO): platform_buoyancy_position 850.763306 cc
2022-01-20T14:11:31.883Z,1642687891.883 [Reporter](INFO): platform_buoyancy_position 857.209717 cc
2022-01-20T14:11:32.364Z,1642687892.364 [Reporter](INFO): platform_buoyancy_position 863.521729 cc
2022-01-20T14:11:32.711Z,1642687892.711 [Reporter](INFO): platform_buoyancy_position 867.819336 cc
2022-01-20T14:11:33.152Z,1642687893.152 [Reporter](INFO): platform_buoyancy_position 872.654175 cc
2022-01-20T14:11:33.553Z,1642687893.553 [Reporter](INFO): platform_buoyancy_position 878.697632 cc
2022-01-20T14:11:33.883Z,1642687893.883 [Reporter](INFO): platform_buoyancy_position 882.860962 cc
2022-01-20T14:11:34.303Z,1642687894.303 [Reporter](INFO): platform_buoyancy_position 887.158569 cc
2022-01-20T14:11:34.715Z,1642687894.715 [Reporter](INFO): platform_buoyancy_position 893.739258 cc
2022-01-20T14:11:35.135Z,1642687895.135 [Reporter](INFO): platform_buoyancy_position 899.917114 cc
2022-01-20T14:11:35.499Z,1642687895.499 [Reporter](INFO): platform_buoyancy_position 904.483276 cc
2022-01-20T14:11:35.916Z,1642687895.916 [Reporter](INFO): platform_buoyancy_position 910.795410 cc
2022-01-20T14:11:36.331Z,1642687896.331 [Reporter](INFO): platform_buoyancy_position 915.227295 cc
2022-01-20T14:11:36.727Z,1642687896.727 [Reporter](INFO): platform_buoyancy_position 919.659180 cc
2022-01-20T14:11:37.171Z,1642687897.171 [Reporter](INFO): platform_buoyancy_position 925.837036 cc
2022-01-20T14:11:37.555Z,1642687897.555 [Reporter](INFO): platform_buoyancy_position 931.074707 cc
2022-01-20T14:11:37.979Z,1642687897.979 [Reporter](INFO): platform_buoyancy_position 936.580933 cc
2022-01-20T14:11:38.460Z,1642687898.460 [Reporter](INFO): platform_buoyancy_position 943.027344 cc
2022-01-20T14:11:38.792Z,1642687898.792 [Reporter](INFO): platform_buoyancy_position 947.324951 cc
2022-01-20T14:11:40.299Z,1642687900.299 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-01-20T14:12:30.399Z,1642687950.399 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:12:30.400Z,1642687950.400 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:12:30.402Z,1642687950.402 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:12:30.808Z,1642687950.808 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141229.108992,06,522,21,0179,0150,194,02,02,01,02,-1,-01,-01,1,3,5,0,150,72.2,-2.23,-100,5.23,-01,-0.77,-12,14500,4000*69

2022-01-20T14:12:55.467Z,1642687975.467 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:12:55.468Z,1642687975.468 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:12:55.469Z,1642687975.469 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:12:55.851Z,1642687975.851 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141255.104128,06,1707,26,0099,0150,261,02,02,01,02,-1,-01,-01,1,3,1,0,150,-100.0,0.21,-100,2.79,-01,-3.42,-2147483648,14500,4000*6B

2022-01-20T14:13:23.737Z,1642688003.737 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for altitude
2022-01-20T14:13:31.407Z,1642688011.407 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:13:31.412Z,1642688011.412 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141329.089544,06,868,23,0100,0150,149,04,04,01,04,4,-01,-01,2,3,1,0,150,4.2,12.93,-100,-9.93,-01,0.04,48,14500,4000*46

2022-01-20T14:13:41.504Z,1642688021.504 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:13:41.509Z,1642688021.509 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141341.089750,06,2180,27,0111,0150,186,01,01,01,02,-1,-01,-01,1,3,1,0,150,17.1,0.99,-100,2.01,-01,0.78,54,14500,4000*7B

2022-01-20T14:13:53.296Z,1642688033.296 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-20T14:08:53.1Z
2022-01-20T14:13:53.296Z,1642688033.296 [Default:CheckIn:Read_GPS] Stopped
2022-01-20T14:13:53.296Z,1642688033.296 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-20T14:13:53.647Z,1642688033.647 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-01-20T14:13:57.080Z,1642688037.080 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20220120T140546/Courier0004.lzma
2022-01-20T14:13:59.087Z,1642688039.087 [DataOverHttps](INFO): Moved sent file to Logs/20220120T140546/Courier0004.lzma.bak
2022-01-20T14:13:59.087Z,1642688039.087 [DataOverHttps](INFO): SBD MOMSN=1149088
2022-01-20T14:14:08.125Z,1642688048.125 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20220120T140546/Express0005.lzma
2022-01-20T14:14:09.127Z,1642688049.127 [DataOverHttps](INFO): Moved sent file to Logs/20220120T140546/Express0005.lzma.bak
2022-01-20T14:14:09.127Z,1642688049.127 [DataOverHttps](INFO): SBD MOMSN=1149090
2022-01-20T14:14:10.201Z,1642688050.201 [Default:CheckIn:Read_Iridium] Stopped
2022-01-20T14:14:10.201Z,1642688050.201 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-20T14:14:10.201Z,1642688050.201 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-20T14:14:25.606Z,1642688065.606 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-20T14:14:25.943Z,1642688065.943 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-01-20T14:14:25.946Z,1642688065.946 [BPC1](INFO): Received data from all battery sticks.
2022-01-20T14:14:37.657Z,1642688077.657 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:14:37.657Z,1642688077.657 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:14:37.659Z,1642688077.659 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:14:38.059Z,1642688078.059 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141437.101960,06,1298,25,0111,0150,246,02,02,01,02,-1,-01,-01,1,3,1,0,150,-100.0,0.06,-100,2.94,-01,-2.95,-2147483648,14500,4000*61

2022-01-20T14:15:21.697Z,1642688121.697 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:15:21.702Z,1642688121.702 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141521.085391,06,1248,24,0100,0150,261,02,02,01,02,-1,-01,-01,1,3,1,0,150,8.1,0.49,-100,2.51,-01,-0.16,64,14500,4000*63

2022-01-20T14:15:29.776Z,1642688129.776 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:15:29.781Z,1642688129.781 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141529.083590,06,1340,25,0096,0150,186,01,01,01,01,-1,-01,-01,1,3,1,0,150,24.9,0.92,-100,2.08,-01,0.34,54,14500,4000*77

2022-01-20T14:15:39.468Z,1642688139.468 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:15:39.874Z,1642688139.874 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141539.086227,06,641,22,0134,0150,208,01,01,01,01,-1,-01,-01,1,3,1,0,150,21.5,0.91,-100,2.09,-01,-0.65,57,14500,4000*67

2022-01-20T14:15:53.608Z,1642688153.608 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:15:53.612Z,1642688153.612 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141553.087444,06,1503,25,0091,0150,149,04,04,01,04,-1,-01,-01,1,3,6,0,150,12.3,0.09,-100,2.91,-01,-0.85,48,14500,4000*56

2022-01-20T14:16:21.486Z,1642688181.486 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:16:21.895Z,1642688181.895 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141621.091830,06,1827,26,0096,0150,246,02,02,01,02,-1,-01,-01,1,3,3,0,150,-4.8,0.11,-100,2.89,-01,-0.90,72,14500,4000*44

2022-01-20T14:16:32.386Z,1642688192.386 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:16:32.390Z,1642688192.390 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141631.087900,06,955,23,0104,0150,261,02,02,01,02,6,-01,-01,2,3,1,0,150,-0.1,0.51,04,2.49,-01,-4.04,64,14500,4000*47

2022-01-20T14:16:49.769Z,1642688209.769 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:16:49.769Z,1642688209.769 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:16:49.771Z,1642688209.771 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:16:50.174Z,1642688210.174 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141649.110843,06,882,23,0117,0150,246,02,02,01,02,-1,-01,-01,1,3,1,0,150,-100.0,-0.06,-100,3.06,-01,0.94,-2147483648,14500,4000*53

2022-01-20T14:16:59.459Z,1642688219.459 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:16:59.863Z,1642688219.863 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141659.082656,06,1348,25,0115,0150,194,01,01,01,02,-1,-01,-01,1,3,1,0,150,18.0,-0.02,-100,3.02,-01,-1.12,55,14500,4000*79

2022-01-20T14:17:35.816Z,1642688255.816 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:17:35.820Z,1642688255.820 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141735.090940,06,1916,26,0097,0150,261,02,02,01,02,-1,-01,-01,1,3,7,0,150,9.0,-0.03,-100,3.03,-01,-0.59,64,14500,4000*43

2022-01-20T14:18:07.732Z,1642688287.732 [Micromodem](INFO): Nmea in: $CAMSG,Error accessing coproc,4*51

2022-01-20T14:18:07.736Z,1642688287.736 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141807.094581,06,440,26,0102,0150,261,02,02,00,02,-1,-01,-01,4,3,0,0,-999,-99.9,-9.99,-999,-9.99,-01,0.00,-999,14500,4000*55

2022-01-20T14:18:23.494Z,1642688303.494 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:18:23.494Z,1642688303.494 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:18:23.496Z,1642688303.496 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:18:23.905Z,1642688303.905 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141823.093981,06,2225,27,0096,0150,261,02,02,01,02,-1,-01,-01,1,3,1,0,150,-100.0,0.40,-100,2.60,-01,-1.86,-2147483648,14500,4000*69

2022-01-20T14:18:59.047Z,1642688339.047 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2022-01-20T14:19:09.154Z,1642688349.154 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2022-01-20T14:19:10.778Z,1642688350.778 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-01-20T14:19:10.778Z,1642688350.778 [Default:CheckIn:C.Wait] Stopped
2022-01-20T14:19:10.778Z,1642688350.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-20T14:19:10.779Z,1642688350.779 [Default:CheckIn:D] Running Loop=1
2022-01-20T14:19:11.210Z,1642688351.210 [Default:CheckIn:D] Stopped
2022-01-20T14:19:11.210Z,1642688351.210 [Default:CheckIn:E] Running Loop=1
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.531769 min 
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn:E] Stopped
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn] Stopped
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn](INFO): Running loop #2
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn] Running Loop=2
2022-01-20T14:19:11.584Z,1642688351.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-01-20T14:19:11.585Z,1642688351.585 [Default:CheckIn:Read_GPS] Running Loop=1
2022-01-20T14:19:13.187Z,1642688353.187 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-01-20T14:19:13.187Z,1642688353.187 [NAL9602] Data Fault, FailCount= 1
2022-01-20T14:19:13.187Z,1642688353.187 [NAL9602](ERROR): Data Fault
2022-01-20T14:19:13.255Z,1642688353.255 [CBIT](ERROR): Data Fault in component: NAL9602
2022-01-20T14:19:13.587Z,1642688353.587 [NAL9602](INFO): Powering down
2022-01-20T14:19:14.428Z,1642688354.428 [CBIT](INFO): Clearing failed state for component NAL9602
2022-01-20T14:19:14.428Z,1642688354.428 [NAL9602] No Fault, FailCount= 1
2022-01-20T14:19:28.135Z,1642688368.135 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:19:28.135Z,1642688368.135 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:19:28.136Z,1642688368.136 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:19:28.535Z,1642688368.535 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141927.093270,06,1407,25,0095,0150,208,01,01,01,01,-1,-01,-01,1,3,7,0,150,68.0,-1.49,-100,4.49,-01,-0.42,-6,14500,4000*67

2022-01-20T14:19:32.173Z,1642688372.173 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:19:32.173Z,1642688372.173 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:19:32.175Z,1642688372.175 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:19:32.583Z,1642688372.583 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141931.106052,06,573,21,0245,0150,186,01,01,01,02,-1,-01,-01,1,3,5,0,150,71.8,-1.39,-100,4.39,-01,-0.34,-12,14500,4000*67

2022-01-20T14:19:43.891Z,1642688383.891 [NAL9602](INFO): Powering up NAL9602
2022-01-20T14:19:51.156Z,1642688391.156 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:19:51.160Z,1642688391.160 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120141949.091426,06,516,21,0095,0150,246,02,02,01,02,6,-01,-01,2,3,2,0,150,-0.8,-0.69,04,3.69,-01,-4.37,63,14500,4000*64

2022-01-20T14:19:54.790Z,1642688394.790 [NAL9602](INFO): NAL9602 initialized
2022-01-20T14:21:14.379Z,1642688474.379 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:21:14.383Z,1642688474.383 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142111.092406,06,1363,25,0090,0150,261,02,02,01,02,6,-01,-01,2,3,4,0,150,2.0,-0.89,04,3.89,-01,-2.62,64,14500,4000*72

2022-01-20T14:22:21.453Z,1642688541.453 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:22:21.855Z,1642688541.855 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142221.107172,06,715,22,0092,0150,186,01,01,01,01,-1,-01,-01,1,3,6,0,150,14.5,-0.02,-100,3.02,-01,-0.15,65,14500,4000*43

2022-01-20T14:23:08.710Z,1642688588.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=31182, MT Status=0, MTMSN=0
2022-01-20T14:23:08.710Z,1642688588.710 [NAL9602](INFO): No messages in MT queue
2022-01-20T14:23:11.959Z,1642688591.959 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:23:11.960Z,1642688591.960 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:23:11.961Z,1642688591.961 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:23:12.351Z,1642688592.351 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142311.103335,06,434,26,0114,0150,186,01,01,00,01,-1,-01,-01,1,3,3,0,150,60.2,2.96,-100,0.04,-01,-0.81,-6,14500,4000*7D

2022-01-20T14:23:17.605Z,1642688597.605 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:23:17.611Z,1642688597.611 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:23:17.613Z,1642688597.613 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:23:18.005Z,1642688598.005 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142317.098234,06,1258,24,0179,0150,246,02,02,01,02,-1,-01,-01,1,3,1,0,150,-100.0,0.25,-100,2.75,-01,-0.86,-2147483648,14500,4000*61

2022-01-20T14:23:57.603Z,1642688637.603 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:23:57.608Z,1642688637.608 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142355.099255,06,1697,26,0129,0150,261,02,02,01,02,4,-01,-01,2,3,1,0,150,2.0,12.86,-100,-9.86,-01,-0.34,64,14500,4000*52

2022-01-20T14:24:11.776Z,1642688651.776 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-01-20T14:19:11.6Z
2022-01-20T14:24:11.776Z,1642688651.776 [Default:CheckIn:Read_GPS] Stopped
2022-01-20T14:24:11.776Z,1642688651.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-01-20T14:24:15.572Z,1642688655.572 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20220120T140546/Courier0007.lzma
2022-01-20T14:24:16.575Z,1642688656.575 [DataOverHttps](INFO): Moved sent file to Logs/20220120T140546/Courier0007.lzma.bak
2022-01-20T14:24:16.575Z,1642688656.575 [DataOverHttps](INFO): SBD MOMSN=1149103
2022-01-20T14:24:22.240Z,1642688662.240 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:24:22.655Z,1642688662.655 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142421.101203,06,728,22,0095,0150,208,01,01,01,02,1,-01,-01,2,4,3,0,150,-31.8,1.65,03,1.35,-01,-0.31,57,14500,4000*71

2022-01-20T14:24:23.861Z,1642688663.861 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:24:23.861Z,1642688663.861 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:24:24.266Z,1642688664.266 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:24:24.666Z,1642688664.666 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142423.103955,06,257,24,0093,0150,186,01,01,00,02,-1,-01,-01,1,3,7,0,150,66.5,2.31,-100,0.69,-01,0.79,-12,14500,4000*67

2022-01-20T14:24:30.633Z,1642688670.633 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20220120T140546/Express0008.lzma
2022-01-20T14:24:31.635Z,1642688671.635 [DataOverHttps](INFO): Moved sent file to Logs/20220120T140546/Express0008.lzma.bak
2022-01-20T14:24:31.635Z,1642688671.635 [DataOverHttps](INFO): SBD MOMSN=1149285
2022-01-20T14:24:32.763Z,1642688672.763 [Default:CheckIn:Read_Iridium] Stopped
2022-01-20T14:24:32.764Z,1642688672.764 [Default:CheckIn:C.Wait] Running Loop=1
2022-01-20T14:24:32.764Z,1642688672.764 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-01-20T14:24:41.635Z,1642688681.635 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:24:41.640Z,1642688681.640 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142441.096639,06,1481,25,0101,0150,261,02,02,01,02,-1,-01,-01,1,3,8,0,150,6.1,-0.01,-100,3.01,-01,-3.18,65,14500,4000*4F

2022-01-20T14:24:43.252Z,1642688683.252 [NAL9602](INFO): Not Powering down - fast GPS
2022-01-20T14:25:27.687Z,1642688727.687 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:25:27.687Z,1642688727.687 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:25:27.689Z,1642688727.689 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:25:28.096Z,1642688728.096 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142527.101612,06,251,23,0135,0150,194,03,03,00,03,-1,-01,-01,1,3,1,0,150,-100.0,0.09,-100,2.91,-01,-2.76,-2147483648,14500,4000*57

2022-01-20T14:25:33.751Z,1642688733.751 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:25:33.756Z,1642688733.756 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142533.100833,06,848,23,0151,0150,208,01,01,01,01,-1,-01,-01,1,3,1,0,150,21.1,1.06,-100,1.94,-01,-0.90,57,14500,4000*6D

2022-01-20T14:26:17.710Z,1642688777.710 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:26:17.715Z,1642688777.715 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142617.108376,06,546,21,0110,0150,194,01,01,01,01,-1,-01,-01,1,3,4,0,150,23.8,0.05,-100,2.95,-01,-0.66,55,14500,4000*6D

2022-01-20T14:26:51.656Z,1642688811.656 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:26:51.661Z,1642688811.661 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142651.085107,06,1423,25,0106,0150,186,01,01,01,01,-1,-01,-01,1,3,1,0,150,15.4,0.63,-100,2.37,-01,-2.12,63,14500,4000*5C

2022-01-20T14:27:10.645Z,1642688830.645 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:27:10.645Z,1642688830.645 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:27:10.647Z,1642688830.647 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:27:11.053Z,1642688831.053 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142709.099045,06,906,23,0121,0150,261,02,02,01,02,-1,-01,-01,1,3,5,0,150,76.6,0.52,-100,2.48,-01,0.84,-12,14500,4000*69

2022-01-20T14:27:28.861Z,1642688848.861 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2022-01-20T14:27:29.228Z,1642688849.228 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:27:29.631Z,1642688849.631 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142727.081966,06,872,23,0116,0150,208,01,01,01,01,4,-01,-01,2,3,1,0,150,-2.4,13.38,-100,-10.38,-01,-1.62,65,14500,4000*73

2022-01-20T14:27:54.693Z,1642688874.693 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC, Data Header,2*00

2022-01-20T14:27:54.699Z,1642688874.699 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142753.079777,06,1783,26,0103,0150,141,01,01,01,01,1,-01,-01,2,3,1,0,150,-0.8,11.52,-100,-8.52,-01,-0.36,47,14500,4000*76

2022-01-20T14:27:57.508Z,1642688877.508 [Micromodem](INFO): Nmea in: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error

2022-01-20T14:27:57.508Z,1642688877.508 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,COP , 213,COPROC_par_read_parobj: size error
 expected 41
2022-01-20T14:27:57.510Z,1642688877.510 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:27:57.913Z,1642688877.913 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142757.099957,06,780,22,0170,0150,194,01,01,01,02,-1,-01,-01,1,3,2,0,150,66.8,0.40,-100,2.60,-01,-1.91,-12,14500,4000*4D

2022-01-20T14:28:35.480Z,1642688915.480 [Micromodem](INFO): Nmea in: $CAMSG,Bad Modulation Header,0*31

2022-01-20T14:28:35.485Z,1642688915.485 [Micromodem](INFO): Nmea in: $CACST,6,1,20220120142835.083804,06,1280,25,0109,0150,194,03,03,01,03,-1,-01,-01,1,3,3,0,150,11.2,0.08,-100,2.92,-01,-2.51,55,14500,4000*5D

2022-01-20T14:28:42.603Z,1642688922.603 [DataOverHttps](IMPORTANT): SBD MTMSN=20220120T142841
2022-01-20T14:28:46.636Z,1642688926.636 [DataOverHttps](INFO): Received command: restart app
2022-01-20T14:28:46.639Z,1642688926.639 [CommandExec](IMPORTANT): got command restart application
2022-01-20T14:28:47.641Z,1642688927.641 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2022-01-20T14:28:47.642Z,1642688927.642 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:47.642Z,1642688927.642 [CommandExec](INFO): Uninitializing the command executive.
2022-01-20T14:28:47.642Z,1642688927.642 [CommandExec](INFO): Uninitializing the command scheduler.
2022-01-20T14:28:47.642Z,1642688927.642 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:47.733Z,1642688927.733 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-01-20T14:28:47.733Z,1642688927.733 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-01-20T14:28:47.734Z,1642688927.734 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:47.734Z,1642688927.734 [NavChartDb](INFO): Join timeout helper Thread ID is 1674
2022-01-20T14:28:47.802Z,1642688927.802 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:47.802Z,1642688927.802 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:47.813Z,1642688927.813 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2022-01-20T14:28:47.813Z,1642688927.813 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:47.814Z,1642688927.814 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1675
2022-01-20T14:28:48.157Z,1642688928.157 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:48.158Z,1642688928.158 [WetLabsSeaOWL_UV_A](INFO): Powering down
2022-01-20T14:28:48.158Z,1642688928.158 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:48.173Z,1642688928.173 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-01-20T14:28:48.173Z,1642688928.173 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:48.174Z,1642688928.174 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1676
2022-01-20T14:28:48.813Z,1642688928.813 [CTD_Seabird](INFO): Powering down
2022-01-20T14:28:48.825Z,1642688928.825 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:48.826Z,1642688928.826 [CTD_Seabird](INFO): Powering down
2022-01-20T14:28:48.837Z,1642688928.837 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:48.846Z,1642688928.846 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-01-20T14:28:48.846Z,1642688928.846 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:48.846Z,1642688928.846 [Radio_Surface](INFO): Join timeout helper Thread ID is 1677
2022-01-20T14:28:48.969Z,1642688928.969 [Radio_Surface](INFO): Powering down
2022-01-20T14:28:48.970Z,1642688928.970 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:48.971Z,1642688928.971 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:48.985Z,1642688928.985 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-01-20T14:28:48.986Z,1642688928.986 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:48.986Z,1642688928.986 [Onboard](INFO): Join timeout helper Thread ID is 1678
2022-01-20T14:28:49.222Z,1642688929.222 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:49.222Z,1642688929.222 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.230Z,1642688929.230 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-01-20T14:28:49.230Z,1642688929.230 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.231Z,1642688929.231 [DataOverHttps](INFO): Join timeout helper Thread ID is 1679
2022-01-20T14:28:49.630Z,1642688929.630 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:49.630Z,1642688929.630 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.634Z,1642688929.634 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-01-20T14:28:49.634Z,1642688929.634 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.635Z,1642688929.635 [BackseatComponent](INFO): Join timeout helper Thread ID is 1680
2022-01-20T14:28:49.678Z,1642688929.678 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:49.678Z,1642688929.678 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.698Z,1642688929.698 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-01-20T14:28:49.698Z,1642688929.698 [logger ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.699Z,1642688929.699 [logger](INFO): Join timeout helper Thread ID is 1681
2022-01-20T14:28:49.716Z,1642688929.716 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:49.716Z,1642688929.716 [logger ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.726Z,1642688929.726 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-01-20T14:28:49.726Z,1642688929.726 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.727Z,1642688929.727 [CommandLine](INFO): Join timeout helper Thread ID is 1682
2022-01-20T14:28:49.818Z,1642688929.818 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:49.818Z,1642688929.818 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.826Z,1642688929.826 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-01-20T14:28:49.826Z,1642688929.826 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.827Z,1642688929.827 [CommandExec](INFO): Join timeout helper Thread ID is 1683
2022-01-20T14:28:49.828Z,1642688929.828 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-01-20T14:28:49.828Z,1642688929.828 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:49.828Z,1642688929.828 [controlThread](INFO): Join timeout helper Thread ID is 1684
2022-01-20T14:28:50.001Z,1642688930.001 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-01-20T14:28:50.002Z,1642688930.002 [controlThread](DEBUG): Uninitializing ControlThread
2022-01-20T14:28:50.002Z,1642688930.002 [AHRS_M2](INFO): Powering down
2022-01-20T14:28:50.074Z,1642688930.074 [Micromodem](INFO): Powering down
2022-01-20T14:28:50.170Z,1642688930.170 [NAL9602](INFO): Powering down
2022-01-20T14:28:50.241Z,1642688930.241 [RDI_Pathfinder](INFO): Powering down
2022-01-20T14:28:50.313Z,1642688930.313 [RDI_PathfinderUp](INFO): Powering down
2022-01-20T14:28:50.315Z,1642688930.315 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-01-20T14:28:50.316Z,1642688930.316 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-01-20T14:28:50.316Z,1642688930.316 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-01-20T14:28:50.317Z,1642688930.317 [MissionManager](INFO): Uninitializing Mission Default
2022-01-20T14:28:50.318Z,1642688930.318 [Default] Stopped
2022-01-20T14:28:50.318Z,1642688930.318 [Default](DEBUG): Aggregate::uninitialize Default
2022-01-20T14:28:50.318Z,1642688930.318 [Default:B.GoToSurface] Stopped
2022-01-20T14:28:50.318Z,1642688930.318 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-01-20T14:28:50.318Z,1642688930.318 [Default:CheckIn] Stopped
2022-01-20T14:28:50.318Z,1642688930.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-01-20T14:28:50.318Z,1642688930.318 [Default:CheckIn:C.Wait] Stopped
2022-01-20T14:28:50.318Z,1642688930.318 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-01-20T14:28:50.321Z,1642688930.321 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-01-20T14:28:50.321Z,1642688930.321 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-01-20T14:28:50.321Z,1642688930.321 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-01-20T14:28:50.322Z,1642688930.322 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-01-20T14:28:50.322Z,1642688930.322 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-01-20T14:28:50.322Z,1642688930.322 [BuoyancyServo](INFO): Powering down
2022-01-20T14:28:50.333Z,1642688930.333 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-01-20T14:28:50.334Z,1642688930.334 [ElevatorServo](INFO): Powering down
2022-01-20T14:28:50.334Z,1642688930.334 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-01-20T14:28:50.335Z,1642688930.335 [MassServo](INFO): Powering down
2022-01-20T14:28:50.335Z,1642688930.335 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-01-20T14:28:50.335Z,1642688930.335 [RudderServo](INFO): Powering down
2022-01-20T14:28:50.336Z,1642688930.336 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-01-20T14:28:50.336Z,1642688930.336 [ThrusterHE](INFO): Powering down
2022-01-20T14:28:50.337Z,1642688930.337 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-01-20T14:28:50.337Z,1642688930.337 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-01-20T14:28:50.338Z,1642688930.338 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-01-20T14:28:50.338Z,1642688930.338 [CBIT](DEBUG): Powering off loads.
2022-01-20T14:28:50.349Z,1642688930.349 [CBIT](DEBUG): Disabling WDT.
2022-01-20T14:28:50.361Z,1642688930.361 [CBIT](DEBUG): Opening all GF detection circuits.
2022-01-20T14:28:50.362Z,1642688930.362 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.370Z,1642688930.370 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.374Z,1642688930.374 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.383Z,1642688930.383 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.425Z,1642688930.425 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.473Z,1642688930.473 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.477Z,1642688930.477 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.535Z,1642688930.535 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-01-20T14:28:50.615Z,1642688930.615 [logger ThreadHandler](INFO): Thread cancelled.